builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0173 starttime: 1468659489.81 results: success (0) buildid: 20160715230551 builduid: 59035499692b414eb7967ee1436532f7 revision: c47d2ca42dff8772f2e86be895ff331346a37b6c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-16 01:58:09.806508) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-16 01:58:09.807044) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-16 01:58:09.807385) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-16 01:58:09.840161) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-16 01:58:09.840608) ========= 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.V0fwv76H58/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.IE2FZyiDpT/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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-07-16 01:58:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 10.4M=0.001s 2016-07-16 01:58:09 (10.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.092294 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-16 01:58:09.958510) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-16 01:58:09.959255) ========= 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.V0fwv76H58/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.IE2FZyiDpT/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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.021122 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-16 01:58:10.011702) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-16 01:58:10.012123) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev c47d2ca42dff8772f2e86be895ff331346a37b6c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev c47d2ca42dff8772f2e86be895ff331346a37b6c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.V0fwv76H58/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.IE2FZyiDpT/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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-07-16 01:58:10,082 truncating revision to first 12 chars 2016-07-16 01:58:10,082 Setting DEBUG logging. 2016-07-16 01:58:10,082 attempt 1/10 2016-07-16 01:58:10,082 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/c47d2ca42dff?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-16 01:58:11,827 unpacking tar archive at: fx-team-c47d2ca42dff/testing/mozharness/ program finished with exit code 0 elapsedTime=2.014733 ========= master_lag: 0.13 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-16 01:58:12.154039) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-16 01:58:12.154414) ========= script_repo_revision: c47d2ca42dff8772f2e86be895ff331346a37b6c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-16 01:58:12.154879) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-16 01:58:12.155184) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-16 01:58:12.176234) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-07-16 01:58:12.177013) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.V0fwv76H58/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.IE2FZyiDpT/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 01:58:12 INFO - MultiFileLogger online at 20160716 01:58:12 in /builds/slave/test 01:58:12 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 01:58:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:58:12 INFO - {'append_to_log': False, 01:58:12 INFO - 'base_work_dir': '/builds/slave/test', 01:58:12 INFO - 'blob_upload_branch': 'Fx-Team', 01:58:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:58:12 INFO - 'branch': 'Fx-Team', 01:58:12 INFO - 'buildbot_json_path': 'buildprops.json', 01:58:12 INFO - 'config_files': ('talos/mac_config.py',), 01:58:12 INFO - 'default_actions': ('clobber', 01:58:12 INFO - 'read-buildbot-config', 01:58:12 INFO - 'download-and-extract', 01:58:12 INFO - 'populate-webroot', 01:58:12 INFO - 'create-virtualenv', 01:58:12 INFO - 'install', 01:58:12 INFO - 'run-tests'), 01:58:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:58:12 INFO - 'download_minidump_stackwalk': True, 01:58:12 INFO - 'download_symbols': 'ondemand', 01:58:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:58:12 INFO - 'tooltool.py': '/tools/tooltool.py', 01:58:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:58:12 INFO - '/tools/misc-python/virtualenv.py')}, 01:58:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:58:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:58:12 INFO - 'installer_path': 'installer.exe', 01:58:12 INFO - 'log_level': 'info', 01:58:12 INFO - 'log_name': 'talos', 01:58:12 INFO - 'log_to_console': True, 01:58:12 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:58:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:58:12 INFO - 'opt_config_files': (), 01:58:12 INFO - 'pip_index': False, 01:58:12 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:58:12 INFO - 'cmd': ('bash', 01:58:12 INFO - '-c', 01:58:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:58:12 INFO - 'enabled': True, 01:58:12 INFO - 'halt_on_failure': False, 01:58:12 INFO - 'name': 'check_screen_resolution'},), 01:58:12 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:58:12 INFO - 'cmd': ('bash', 01:58:12 INFO - '-c', 01:58:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:58:12 INFO - 'enabled': True, 01:58:12 INFO - 'halt_on_failure': False, 01:58:12 INFO - 'name': 'check_screen_resolution'},), 01:58:12 INFO - 'run_cmd_checks_enabled': True, 01:58:12 INFO - 'sps_profile': False, 01:58:12 INFO - 'sps_profile_interval': 0, 01:58:12 INFO - 'suite': 'dromaeojs', 01:58:12 INFO - 'system_bits': '32', 01:58:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:58:12 INFO - 'title': 't-yosemite-r7-0173', 01:58:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:58:12 INFO - 'use_talos_json': True, 01:58:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:58:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:58:12 INFO - 'work_dir': 'build'} 01:58:12 INFO - ##### 01:58:12 INFO - ##### Running clobber step. 01:58:12 INFO - ##### 01:58:12 INFO - Running pre-action listener: _resource_record_pre_action 01:58:12 INFO - Running main action method: clobber 01:58:12 INFO - rmtree: /builds/slave/test/build 01:58:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:58:13 INFO - Running post-action listener: _resource_record_post_action 01:58:13 INFO - ##### 01:58:13 INFO - ##### Finished clobber step (success) 01:58:13 INFO - ##### 01:58:13 INFO - ##### 01:58:13 INFO - ##### Running read-buildbot-config step. 01:58:13 INFO - ##### 01:58:13 INFO - Running pre-action listener: _resource_record_pre_action 01:58:13 INFO - Running main action method: read_buildbot_config 01:58:13 INFO - Using buildbot properties: 01:58:13 INFO - { 01:58:13 INFO - "project": "", 01:58:13 INFO - "product": "firefox", 01:58:13 INFO - "script_repo_revision": "production", 01:58:13 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 01:58:13 INFO - "repository": "", 01:58:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 01:58:13 INFO - "buildid": "20160715230551", 01:58:13 INFO - "pgo_build": "False", 01:58:13 INFO - "basedir": "/builds/slave/test", 01:58:13 INFO - "buildnumber": 800, 01:58:13 INFO - "slavename": "t-yosemite-r7-0173", 01:58:13 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 01:58:13 INFO - "platform": "yosemite_r7", 01:58:13 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 01:58:13 INFO - "branch": "fx-team", 01:58:13 INFO - "repo_path": "integration/fx-team", 01:58:13 INFO - "revision": "c47d2ca42dff8772f2e86be895ff331346a37b6c", 01:58:13 INFO - "stage_platform": "macosx64", 01:58:13 INFO - "builduid": "59035499692b414eb7967ee1436532f7", 01:58:13 INFO - "slavebuilddir": "test" 01:58:13 INFO - } 01:58:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/Yjn1i2tSRw6h0GFygfc2gQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 01:58:13 INFO - Running post-action listener: _resource_record_post_action 01:58:13 INFO - ##### 01:58:13 INFO - ##### Finished read-buildbot-config step (success) 01:58:13 INFO - ##### 01:58:13 INFO - ##### 01:58:13 INFO - ##### Running download-and-extract step. 01:58:13 INFO - ##### 01:58:13 INFO - Running pre-action listener: _resource_record_pre_action 01:58:13 INFO - Running main action method: download_and_extract 01:58:13 INFO - mkdir: /builds/slave/test/build/tests 01:58: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:58:13 INFO - https://queue.taskcluster.net/v1/task/Yjn1i2tSRw6h0GFygfc2gQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 01:58:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yjn1i2tSRw6h0GFygfc2gQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 01:58:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yjn1i2tSRw6h0GFygfc2gQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 01:58:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yjn1i2tSRw6h0GFygfc2gQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 01:58:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yjn1i2tSRw6h0GFygfc2gQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 01:58:16 INFO - Downloaded 1199 bytes. 01:58:16 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 01:58:16 INFO - Using the following test package requirements: 01:58:16 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 01:58:16 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 01:58:16 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 01:58:16 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 01:58:16 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 01:58:16 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 01:58:16 INFO - u'jsshell-mac.zip'], 01:58:16 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 01:58:16 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 01:58:16 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 01:58:16 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 01:58:16 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 01:58:16 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 01:58:16 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 01:58:16 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 01:58:16 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 01:58:16 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 01:58:16 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 01:58:16 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 01:58:16 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:58:16 INFO - https://queue.taskcluster.net/v1/task/Yjn1i2tSRw6h0GFygfc2gQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 01:58:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yjn1i2tSRw6h0GFygfc2gQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 01:58:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yjn1i2tSRw6h0GFygfc2gQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 01:58:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yjn1i2tSRw6h0GFygfc2gQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 01:58:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yjn1i2tSRw6h0GFygfc2gQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 01:58:17 INFO - Downloaded 18829462 bytes. 01:58:17 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 01:58:17 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 01:58:20 INFO - Return code: 0 01:58:20 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 01:58:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:58:20 INFO - https://queue.taskcluster.net/v1/task/Yjn1i2tSRw6h0GFygfc2gQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 01:58:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yjn1i2tSRw6h0GFygfc2gQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 01:58:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yjn1i2tSRw6h0GFygfc2gQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 01:58:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yjn1i2tSRw6h0GFygfc2gQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 01:58:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yjn1i2tSRw6h0GFygfc2gQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 01:58:21 INFO - Downloaded 18829462 bytes. 01:58:21 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 01:58:21 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 01:58:25 INFO - Return code: 0 01:58: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:58:25 INFO - https://queue.taskcluster.net/v1/task/Yjn1i2tSRw6h0GFygfc2gQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 01:58:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yjn1i2tSRw6h0GFygfc2gQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 01:58:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yjn1i2tSRw6h0GFygfc2gQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 01:58:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yjn1i2tSRw6h0GFygfc2gQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 01:58:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yjn1i2tSRw6h0GFygfc2gQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 01:58:26 INFO - Downloaded 14403317 bytes. 01:58:26 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 01:58:26 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 01:58:26 INFO - Return code: 0 01:58:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:58:26 INFO - https://queue.taskcluster.net/v1/task/Yjn1i2tSRw6h0GFygfc2gQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 01:58:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yjn1i2tSRw6h0GFygfc2gQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 01:58:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yjn1i2tSRw6h0GFygfc2gQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 01:58:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yjn1i2tSRw6h0GFygfc2gQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 01:58:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Yjn1i2tSRw6h0GFygfc2gQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 01:58:30 INFO - Downloaded 112782427 bytes. 01:58:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Yjn1i2tSRw6h0GFygfc2gQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 01:58:30 INFO - mkdir: /builds/slave/test/properties 01:58:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:58:30 INFO - Writing to file /builds/slave/test/properties/build_url 01:58:30 INFO - Contents: 01:58:30 INFO - build_url:https://queue.taskcluster.net/v1/task/Yjn1i2tSRw6h0GFygfc2gQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 01:58:31 INFO - Running post-action listener: _resource_record_post_action 01:58:31 INFO - Running post-action listener: set_extra_try_arguments 01:58:31 INFO - ##### 01:58:31 INFO - ##### Finished download-and-extract step (success) 01:58:31 INFO - ##### 01:58:31 INFO - ##### 01:58:31 INFO - ##### Running populate-webroot step. 01:58:31 INFO - ##### 01:58:31 INFO - Running pre-action listener: _resource_record_pre_action 01:58:31 INFO - Running main action method: populate_webroot 01:58:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:58:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:58:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:58:31 INFO - '--tppagecycles', 01:58:31 INFO - '7'], 01:58:31 INFO - 'tests': ['tsvgm']}}, 01:58:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:58:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:58:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:58:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:58:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:58:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:58:31 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:58:31 INFO - 'talos_options': ['--disable-e10s'], 01:58:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:58:31 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:58:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:58:31 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:58:31 INFO - 'talos_options': ['--disable-e10s'], 01:58:31 INFO - 'tests': ['damp', 'tps']}, 01:58:31 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:58:31 INFO - 'tests': ['damp', 'tps']}, 01:58:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:58:31 INFO - 'tests': ['dromaeo_dom']}, 01:58:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:58:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:58:31 INFO - 'tests': ['basic_compositor_video']}, 01:58:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:58:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:58:31 INFO - 'tests': ['a11yr', 01:58:31 INFO - 'ts_paint', 01:58:31 INFO - 'tpaint', 01:58:31 INFO - 'sessionrestore', 01:58:31 INFO - 'sessionrestore_no_auto_restore', 01:58:31 INFO - 'tabpaint']}, 01:58:31 INFO - 'other-e10s': {'tests': ['a11yr', 01:58:31 INFO - 'ts_paint', 01:58:31 INFO - 'tpaint', 01:58:31 INFO - 'sessionrestore', 01:58:31 INFO - 'sessionrestore_no_auto_restore', 01:58:31 INFO - 'tabpaint']}, 01:58:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:58:31 INFO - 'ts_paint', 01:58:31 INFO - 'tpaint', 01:58:31 INFO - 'sessionrestore', 01:58:31 INFO - 'sessionrestore_no_auto_restore', 01:58:31 INFO - 'tabpaint']}, 01:58:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:58:31 INFO - 'ts_paint', 01:58:31 INFO - 'tpaint', 01:58:31 INFO - 'sessionrestore', 01:58:31 INFO - 'sessionrestore_no_auto_restore', 01:58:31 INFO - 'tabpaint']}, 01:58:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:58:31 INFO - 'tests': ['a11yr', 01:58:31 INFO - 'ts_paint', 01:58:31 INFO - 'tpaint', 01:58:31 INFO - 'sessionrestore', 01:58:31 INFO - 'sessionrestore_no_auto_restore', 01:58:31 INFO - 'tabpaint']}, 01:58:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:58:31 INFO - 'tests': ['a11yr', 01:58:31 INFO - 'ts_paint', 01:58:31 INFO - 'tpaint', 01:58:31 INFO - 'sessionrestore', 01:58:31 INFO - 'sessionrestore_no_auto_restore', 01:58:31 INFO - 'tabpaint']}, 01:58:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:58:31 INFO - 'tests': ['tsvgx', 01:58:31 INFO - 'tsvgr_opacity', 01:58:31 INFO - 'tart', 01:58:31 INFO - 'tscrollx', 01:58:31 INFO - 'cart']}, 01:58:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:58:31 INFO - 'tsvgr_opacity', 01:58:31 INFO - 'tart', 01:58:31 INFO - 'tscrollx', 01:58:31 INFO - 'cart']}, 01:58:31 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:58:31 INFO - 'talos_options': ['--disable-e10s'], 01:58:31 INFO - 'tests': ['tp5o']}, 01:58:31 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:58:31 INFO - 'tests': ['tp5o']}, 01:58:31 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:58:31 INFO - 'talos_options': ['--disable-e10s', 01:58:31 INFO - '--xperf_path', 01:58:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:58:31 INFO - 'tests': ['tp5n']}, 01:58:31 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:58:31 INFO - 'talos_options': ['--xperf_path', 01:58:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:58:31 INFO - 'tests': ['tp5n']}}, 01:58:31 INFO - 'talos.zip': {'path': '', 01:58:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:58:31 INFO - Running post-action listener: _resource_record_post_action 01:58:31 INFO - ##### 01:58:31 INFO - ##### Finished populate-webroot step (success) 01:58:31 INFO - ##### 01:58:31 INFO - ##### 01:58:31 INFO - ##### Running create-virtualenv step. 01:58:31 INFO - ##### 01:58:31 INFO - Running pre-action listener: _resource_record_pre_action 01:58:31 INFO - Running main action method: create_virtualenv 01:58:31 INFO - Creating virtualenv /builds/slave/test/build/venv 01:58:31 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 01:58:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:58:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:58:31 INFO - Using real prefix '/tools/python27' 01:58:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:58:32 INFO - Installing distribute.............................................................................................................................................................................................done. 01:58:34 INFO - Installing pip.................done. 01:58:34 INFO - Return code: 0 01:58:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:58:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:58:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:58:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:58:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:58:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:58:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:58:34 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 0x105d4da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105de2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a14b12e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105cb1d30>, '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 0x105a16db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ca9b20>, '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.V0fwv76H58/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.IE2FZyiDpT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 01:58:34 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 01:58:34 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 01:58:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V0fwv76H58/Render', 01:58:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:58:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:58:34 INFO - 'HOME': '/Users/cltbld', 01:58:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:58:34 INFO - 'LOGNAME': 'cltbld', 01:58:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:58:34 INFO - 'MOZ_NO_REMOTE': '1', 01:58:34 INFO - 'NO_EM_RESTART': '1', 01:58:34 INFO - 'PAGER': '/bin/cat', 01:58:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:58:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:58:34 INFO - 'PWD': '/builds/slave/test', 01:58:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:58:34 INFO - 'SHELL': '/bin/bash', 01:58:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IE2FZyiDpT/Listeners', 01:58:34 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 01:58:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:58:34 INFO - 'USER': 'cltbld', 01:58:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:58:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:58:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:58:34 INFO - 'XPC_FLAGS': '0x0', 01:58:34 INFO - 'XPC_SERVICE_NAME': '0', 01:58:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:58:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:58:35 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 01:58:35 INFO - Cleaning up... 01:58:35 INFO - Return code: 0 01:58:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:58:35 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:58:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:58:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:58:35 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:58:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:58:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:58:35 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 0x105d4da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105de2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a14b12e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105cb1d30>, '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 0x105a16db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ca9b20>, '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.V0fwv76H58/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.IE2FZyiDpT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 01:58:35 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 01:58:35 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 01:58:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V0fwv76H58/Render', 01:58:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:58:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:58:35 INFO - 'HOME': '/Users/cltbld', 01:58:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:58:35 INFO - 'LOGNAME': 'cltbld', 01:58:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:58:35 INFO - 'MOZ_NO_REMOTE': '1', 01:58:35 INFO - 'NO_EM_RESTART': '1', 01:58:35 INFO - 'PAGER': '/bin/cat', 01:58:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:58:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:58:35 INFO - 'PWD': '/builds/slave/test', 01:58:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:58:35 INFO - 'SHELL': '/bin/bash', 01:58:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IE2FZyiDpT/Listeners', 01:58:35 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 01:58:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:58:35 INFO - 'USER': 'cltbld', 01:58:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:58:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:58:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:58:35 INFO - 'XPC_FLAGS': '0x0', 01:58:35 INFO - 'XPC_SERVICE_NAME': '0', 01:58:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:58:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:58:35 INFO - Downloading/unpacking psutil>=3.1.1 01:58:35 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 01:58:35 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 01:58:35 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 01:58:35 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 01:58:35 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 01:58:35 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 01:58:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:58:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:58:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:58:38 INFO - Installing collected packages: psutil 01:58:38 INFO - Running setup.py install for psutil 01:58:38 INFO - building 'psutil._psutil_osx' extension 01:58:38 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 01:58:38 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 01:58:38 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 01:58:38 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 01:58:38 INFO - building 'psutil._psutil_posix' extension 01:58:38 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 01:58:38 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:58:38 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:58:38 INFO - ^ 01:58:38 INFO - 1 warning generated. 01:58:38 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 01:58:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:58:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:58:38 INFO - Successfully installed psutil 01:58:38 INFO - Cleaning up... 01:58:39 INFO - Return code: 0 01:58:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:58:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:58:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:58:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:58:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:58:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:58:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:58:39 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 0x105d4da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105de2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a14b12e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105cb1d30>, '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 0x105a16db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ca9b20>, '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.V0fwv76H58/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.IE2FZyiDpT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 01:58:39 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 01:58:39 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 01:58:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V0fwv76H58/Render', 01:58:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:58:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:58:39 INFO - 'HOME': '/Users/cltbld', 01:58:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:58:39 INFO - 'LOGNAME': 'cltbld', 01:58:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:58:39 INFO - 'MOZ_NO_REMOTE': '1', 01:58:39 INFO - 'NO_EM_RESTART': '1', 01:58:39 INFO - 'PAGER': '/bin/cat', 01:58:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:58:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:58:39 INFO - 'PWD': '/builds/slave/test', 01:58:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:58:39 INFO - 'SHELL': '/bin/bash', 01:58:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IE2FZyiDpT/Listeners', 01:58:39 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 01:58:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:58:39 INFO - 'USER': 'cltbld', 01:58:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:58:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:58:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:58:39 INFO - 'XPC_FLAGS': '0x0', 01:58:39 INFO - 'XPC_SERVICE_NAME': '0', 01:58:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:58:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:58:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:58:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:58:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:58:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:58:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:58:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:58:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:58:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:58:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:58:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:58:41 INFO - Installing collected packages: mozsystemmonitor 01:58:41 INFO - Running setup.py install for mozsystemmonitor 01:58:41 INFO - Successfully installed mozsystemmonitor 01:58:41 INFO - Cleaning up... 01:58:42 INFO - Return code: 0 01:58:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:58:42 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:58:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:58:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:58:42 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:58:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:58:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:58:42 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 0x105d4da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105de2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a14b12e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105cb1d30>, '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 0x105a16db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ca9b20>, '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.V0fwv76H58/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.IE2FZyiDpT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 01:58:42 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 01:58:42 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 01:58:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V0fwv76H58/Render', 01:58:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:58:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:58:42 INFO - 'HOME': '/Users/cltbld', 01:58:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:58:42 INFO - 'LOGNAME': 'cltbld', 01:58:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:58:42 INFO - 'MOZ_NO_REMOTE': '1', 01:58:42 INFO - 'NO_EM_RESTART': '1', 01:58:42 INFO - 'PAGER': '/bin/cat', 01:58:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:58:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:58:42 INFO - 'PWD': '/builds/slave/test', 01:58:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:58:42 INFO - 'SHELL': '/bin/bash', 01:58:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IE2FZyiDpT/Listeners', 01:58:42 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 01:58:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:58:42 INFO - 'USER': 'cltbld', 01:58:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:58:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:58:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:58:42 INFO - 'XPC_FLAGS': '0x0', 01:58:42 INFO - 'XPC_SERVICE_NAME': '0', 01:58:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:58:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:58:42 INFO - Downloading/unpacking jsonschema==2.5.1 01:58:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:58:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:58:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:58:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:58:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:58:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:58:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:58:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:58:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:58:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:58:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:58:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:58:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:58:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:58:45 INFO - Downloading functools32-3.2.3-2.tar.gz 01:58:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:58:45 INFO - warning: no files found matching '*.txt' 01:58:45 INFO - no previously-included directories found matching 'build' 01:58:45 INFO - no previously-included directories found matching 'dist' 01:58:45 INFO - no previously-included directories found matching '.git*' 01:58:45 INFO - Installing collected packages: jsonschema, functools32 01:58:45 INFO - Running setup.py install for functools32 01:58:45 INFO - warning: no files found matching '*.txt' 01:58:45 INFO - no previously-included directories found matching 'build' 01:58:45 INFO - no previously-included directories found matching 'dist' 01:58:45 INFO - no previously-included directories found matching '.git*' 01:58:45 INFO - Successfully installed jsonschema functools32 01:58:45 INFO - Cleaning up... 01:58:45 INFO - Return code: 0 01:58:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:58:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:58:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:58:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:58:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:58:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:58:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:58:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105d4da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105de2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a14b12e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105cb1d30>, '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 0x105a16db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ca9b20>, '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.V0fwv76H58/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.IE2FZyiDpT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 01:58:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 01:58:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 01:58:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V0fwv76H58/Render', 01:58:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:58:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:58:45 INFO - 'HOME': '/Users/cltbld', 01:58:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:58:45 INFO - 'LOGNAME': 'cltbld', 01:58:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:58:45 INFO - 'MOZ_NO_REMOTE': '1', 01:58:45 INFO - 'NO_EM_RESTART': '1', 01:58:45 INFO - 'PAGER': '/bin/cat', 01:58:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:58:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:58:45 INFO - 'PWD': '/builds/slave/test', 01:58:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:58:45 INFO - 'SHELL': '/bin/bash', 01:58:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IE2FZyiDpT/Listeners', 01:58:45 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 01:58:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:58:45 INFO - 'USER': 'cltbld', 01:58:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:58:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:58:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:58:45 INFO - 'XPC_FLAGS': '0x0', 01:58:45 INFO - 'XPC_SERVICE_NAME': '0', 01:58:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:58:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:58:45 INFO - Downloading/unpacking blobuploader==1.2.4 01:58:45 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 01:58:45 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 01:58:45 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 01:58:45 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 01:58:45 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 01:58:45 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 01:58:48 INFO - Downloading blobuploader-1.2.4.tar.gz 01:58:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:58:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:58:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:58:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:58:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:58:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:58:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:58:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:58:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:58:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:58:49 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 01:58:49 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 01:58:49 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 01:58:49 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 01:58:49 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 01:58:49 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 01:58:49 INFO - Downloading docopt-0.6.1.tar.gz 01:58:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:58:49 INFO - Installing collected packages: blobuploader, requests, docopt 01:58:49 INFO - Running setup.py install for blobuploader 01:58:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:58:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:58:49 INFO - Running setup.py install for requests 01:58:50 INFO - Running setup.py install for docopt 01:58:50 INFO - Successfully installed blobuploader requests docopt 01:58:50 INFO - Cleaning up... 01:58:50 INFO - Return code: 0 01:58:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:58:50 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:58:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:58:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:58:50 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:58:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:58:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:58:50 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 0x105d4da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105de2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a14b12e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105cb1d30>, '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 0x105a16db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ca9b20>, '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.V0fwv76H58/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.IE2FZyiDpT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 01:58:50 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 01:58:50 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 01:58:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V0fwv76H58/Render', 01:58:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:58:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:58:50 INFO - 'HOME': '/Users/cltbld', 01:58:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:58:50 INFO - 'LOGNAME': 'cltbld', 01:58:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:58:50 INFO - 'MOZ_NO_REMOTE': '1', 01:58:50 INFO - 'NO_EM_RESTART': '1', 01:58:50 INFO - 'PAGER': '/bin/cat', 01:58:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:58:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:58:50 INFO - 'PWD': '/builds/slave/test', 01:58:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:58:50 INFO - 'SHELL': '/bin/bash', 01:58:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IE2FZyiDpT/Listeners', 01:58:50 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 01:58:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:58:50 INFO - 'USER': 'cltbld', 01:58:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:58:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:58:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:58:50 INFO - 'XPC_FLAGS': '0x0', 01:58:50 INFO - 'XPC_SERVICE_NAME': '0', 01:58:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:58:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:58:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:58:50 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-zsX46i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:58:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:58:50 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-TPztoX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:58:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:58:50 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-5zkBiU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:58:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:58:51 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-BWi8KT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:58:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:58:51 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-2Socr_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:58:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:58:51 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-85LLaF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:58:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:58:51 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-czykrm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:58:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:58:51 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-gXNXQ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:58:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:58:51 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-mFQbO0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:58:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:58:51 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-tTsWzS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:58:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:58:51 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-GEvsbV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:58:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:58:51 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-0h39jc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:58:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:58:51 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-edWnIS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:58:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:58:52 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-coRh54-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:58:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:58:52 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-HeXKpV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:58:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:58:52 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-EsQ7eD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:58:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:58:52 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-DPKTdS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:58:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:58:52 INFO - Running setup.py install for manifestparser 01:58:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:58:52 INFO - Running setup.py install for mozcrash 01:58:52 INFO - Running setup.py install for mozdebug 01:58:52 INFO - Running setup.py install for mozdevice 01:58:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:58:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:58:53 INFO - Running setup.py install for mozfile 01:58:53 INFO - Running setup.py install for mozhttpd 01:58:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:58:53 INFO - Running setup.py install for mozinfo 01:58:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:58:53 INFO - Running setup.py install for mozInstall 01:58:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:58:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:58:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:58:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:58:53 INFO - Running setup.py install for mozleak 01:58:53 INFO - Running setup.py install for mozlog 01:58:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:58:53 INFO - Running setup.py install for moznetwork 01:58:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:58:54 INFO - Running setup.py install for mozprocess 01:58:54 INFO - Running setup.py install for mozprofile 01:58:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:58:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:58:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:58:54 INFO - Running setup.py install for mozrunner 01:58:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:58:54 INFO - Running setup.py install for mozscreenshot 01:58:54 INFO - Running setup.py install for moztest 01:58:54 INFO - Running setup.py install for mozversion 01:58:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:58:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:58:55 INFO - Cleaning up... 01:58:55 INFO - Return code: 0 01:58:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:58:55 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:58:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:58:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:58:55 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:58:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:58:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:58:55 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 0x105d4da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105de2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a14b12e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105cb1d30>, '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 0x105a16db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ca9b20>, '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.V0fwv76H58/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.IE2FZyiDpT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 01:58:55 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 01:58:55 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 01:58:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V0fwv76H58/Render', 01:58:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:58:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:58:55 INFO - 'HOME': '/Users/cltbld', 01:58:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:58:55 INFO - 'LOGNAME': 'cltbld', 01:58:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:58:55 INFO - 'MOZ_NO_REMOTE': '1', 01:58:55 INFO - 'NO_EM_RESTART': '1', 01:58:55 INFO - 'PAGER': '/bin/cat', 01:58:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:58:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:58:55 INFO - 'PWD': '/builds/slave/test', 01:58:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:58:55 INFO - 'SHELL': '/bin/bash', 01:58:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IE2FZyiDpT/Listeners', 01:58:55 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 01:58:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:58:55 INFO - 'USER': 'cltbld', 01:58:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:58:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:58:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:58:55 INFO - 'XPC_FLAGS': '0x0', 01:58:55 INFO - 'XPC_SERVICE_NAME': '0', 01:58:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:58:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:58:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:58:55 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-vLdq8s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:58:55 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)) 01:58:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:58:55 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-nGqhDS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:58:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 01:58:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:58:55 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-Zz4MYJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:58:55 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)) 01:58:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:58:55 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-kmoanE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:58:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 01:58:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:58:55 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-rq18x8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:58:55 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)) 01:58:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:58:55 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-iapSVv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:58:56 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)) 01:58:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:58:56 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-uoPmG8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:58:56 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)) 01:58:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:58:56 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-du8Cfx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:58:56 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)) 01:58:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:58:56 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-26BaK4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:58:56 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)) 01:58:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:58:56 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-UBrn5M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:58:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 01:58:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:58:56 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-21mmrY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:58:56 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)) 01:58:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:58:56 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-vMHjts-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:58:56 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)) 01:58:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:58:56 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-zgI45q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:58:56 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)) 01:58:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:58:56 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-LR19E6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:58:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 01:58:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:58:56 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-b6ujaX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:58:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 01:58:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:58:56 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-X8VFS2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:58:57 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)) 01:58:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:58:57 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-SvFFjc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:58:57 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)) 01:58:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:58:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:58:57 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)) 01:58:57 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:58:57 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:58:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:58: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 01:58: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 01:58: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 01:58: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 01:58: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 01:58: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 01:58:59 INFO - Downloading blessings-1.6.tar.gz 01:58:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:58:59 INFO - Installing collected packages: blessings 01:58:59 INFO - Running setup.py install for blessings 01:58:59 INFO - Successfully installed blessings 01:58:59 INFO - Cleaning up... 01:59:00 INFO - Return code: 0 01:59:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:59:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:59:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:59:00 INFO - Reading from file tmpfile_stdout 01:59:00 INFO - Current package versions: 01:59:00 INFO - blessings == 1.6 01:59:00 INFO - blobuploader == 1.2.4 01:59:00 INFO - docopt == 0.6.1 01:59:00 INFO - functools32 == 3.2.3-2 01:59:00 INFO - jsonschema == 2.5.1 01:59:00 INFO - manifestparser == 1.1 01:59:00 INFO - mozInstall == 1.12 01:59:00 INFO - mozcrash == 0.17 01:59:00 INFO - mozdebug == 0.1 01:59:00 INFO - mozdevice == 0.48 01:59:00 INFO - mozfile == 1.2 01:59:00 INFO - mozhttpd == 0.7 01:59:00 INFO - mozinfo == 0.9 01:59:00 INFO - mozleak == 0.1 01:59:00 INFO - mozlog == 3.2 01:59:00 INFO - moznetwork == 0.27 01:59:00 INFO - mozprocess == 0.23 01:59:00 INFO - mozprofile == 0.28 01:59:00 INFO - mozrunner == 6.12 01:59:00 INFO - mozscreenshot == 0.1 01:59:00 INFO - mozsystemmonitor == 0.3 01:59:00 INFO - moztest == 0.7 01:59:00 INFO - mozversion == 1.4 01:59:00 INFO - psutil == 3.1.1 01:59:00 INFO - requests == 1.2.3 01:59:00 INFO - wsgiref == 0.1.2 01:59:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:59:00 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:59:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:59:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:59:00 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:59:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:59:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:59:00 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 0x105d4da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105de2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a14b12e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105cb1d30>, '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 0x105a16db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ca9b20>, '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.V0fwv76H58/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.IE2FZyiDpT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 01:59:00 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 01:59:00 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 01:59:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V0fwv76H58/Render', 01:59:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:59:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:59:00 INFO - 'HOME': '/Users/cltbld', 01:59:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:59:00 INFO - 'LOGNAME': 'cltbld', 01:59:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:59:00 INFO - 'MOZ_NO_REMOTE': '1', 01:59:00 INFO - 'NO_EM_RESTART': '1', 01:59:00 INFO - 'PAGER': '/bin/cat', 01:59:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:59:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:59:00 INFO - 'PWD': '/builds/slave/test', 01:59:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:59:00 INFO - 'SHELL': '/bin/bash', 01:59:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IE2FZyiDpT/Listeners', 01:59:00 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 01:59:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:59:00 INFO - 'USER': 'cltbld', 01:59:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:59:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:59:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:59:00 INFO - 'XPC_FLAGS': '0x0', 01:59:00 INFO - 'XPC_SERVICE_NAME': '0', 01:59:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:59:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:59:00 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)) 01:59:00 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)) 01:59:00 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)) 01:59:00 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)) 01:59:00 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)) 01:59:00 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)) 01:59:00 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)) 01:59:00 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)) 01:59:00 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)) 01:59:00 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)) 01:59:00 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)) 01:59:00 INFO - Cleaning up... 01:59:00 INFO - Return code: 0 01:59:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:59:00 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:59:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:59:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:59:00 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:59:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:59:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:59:00 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 0x105d4da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105de2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4a14b12e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105cb1d30>, '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 0x105a16db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ca9b20>, '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.V0fwv76H58/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.IE2FZyiDpT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 01:59:00 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 01:59:00 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 01:59:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V0fwv76H58/Render', 01:59:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:59:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:59:00 INFO - 'HOME': '/Users/cltbld', 01:59:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:59:00 INFO - 'LOGNAME': 'cltbld', 01:59:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:59:00 INFO - 'MOZ_NO_REMOTE': '1', 01:59:00 INFO - 'NO_EM_RESTART': '1', 01:59:00 INFO - 'PAGER': '/bin/cat', 01:59:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:59:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:59:00 INFO - 'PWD': '/builds/slave/test', 01:59:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:59:00 INFO - 'SHELL': '/bin/bash', 01:59:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IE2FZyiDpT/Listeners', 01:59:00 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 01:59:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:59:00 INFO - 'USER': 'cltbld', 01:59:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:59:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:59:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:59:00 INFO - 'XPC_FLAGS': '0x0', 01:59:00 INFO - 'XPC_SERVICE_NAME': '0', 01:59:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:59:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:59:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:59:00 INFO - Cleaning up... 01:59:00 INFO - Return code: 0 01:59:00 INFO - Running post-action listener: _resource_record_post_action 01:59:00 INFO - Running post-action listener: _start_resource_monitoring 01:59:00 INFO - Starting resource monitoring. 01:59:00 INFO - ##### 01:59:00 INFO - ##### Finished create-virtualenv step (success) 01:59:00 INFO - ##### 01:59:00 INFO - ##### 01:59:00 INFO - ##### Running install step. 01:59:00 INFO - ##### 01:59:00 INFO - Running pre-action listener: _resource_record_pre_action 01:59:00 INFO - Running main action method: install 01:59:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:59:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:59:01 INFO - Reading from file tmpfile_stdout 01:59:01 INFO - Detecting whether we're running mozinstall >=1.0... 01:59:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:59:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:59:01 INFO - Reading from file tmpfile_stdout 01:59:01 INFO - Output received: 01:59:01 INFO - Usage: mozinstall [options] installer 01:59:01 INFO - Options: 01:59:01 INFO - -h, --help show this help message and exit 01:59:01 INFO - -d DEST, --destination=DEST 01:59:01 INFO - Directory to install application into. [default: 01:59:01 INFO - "/builds/slave/test"] 01:59:01 INFO - --app=APP Application being installed. [default: firefox] 01:59:01 INFO - mkdir: /builds/slave/test/build/application 01:59:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 01:59:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 01:59:35 INFO - Reading from file tmpfile_stdout 01:59:35 INFO - Output received: 01:59:35 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 01:59:35 INFO - Running post-action listener: _resource_record_post_action 01:59:35 INFO - ##### 01:59:35 INFO - ##### Finished install step (success) 01:59:35 INFO - ##### 01:59:35 INFO - ##### 01:59:35 INFO - ##### Running run-tests step. 01:59:35 INFO - ##### 01:59:35 INFO - Running pre-action listener: _resource_record_pre_action 01:59:35 INFO - Running main action method: run_tests 01:59:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:59:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:59:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:59:35 INFO - 2016-07-16 01:59:35.736 screenresolution[2216:24446] starting screenresolution argv=screenresolution get 01:59:35 INFO - 2016-07-16 01:59:35.738 screenresolution[2216:24446] Display 0: 1600x1200x32@60 01:59:35 INFO - 2016-07-16 01:59:35.744 screenresolution[2217:24450] starting screenresolution argv=screenresolution list 01:59:35 INFO - Available Modes on Display 0 01:59:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:59:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:59:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:59:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:59:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:59:35 INFO - Intel Iris: 01:59:35 INFO - Chipset Model: Intel Iris 01:59:35 INFO - Type: GPU 01:59:35 INFO - Bus: Built-In 01:59:35 INFO - VRAM (Dynamic, Max): 1536 MB 01:59:35 INFO - Vendor: Intel (0x8086) 01:59:35 INFO - Device ID: 0x0a2e 01:59:35 INFO - Revision ID: 0x0009 01:59:35 INFO - Displays: 01:59:35 INFO - Display: 01:59:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:59:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:59:35 INFO - Display Serial Number: 1600x1200 60 01:59:35 INFO - Main Display: Yes 01:59:35 INFO - Mirror: Off 01:59:35 INFO - Online: Yes 01:59:35 INFO - Rotation: Supported 01:59:35 INFO - Return code: 0 01:59:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:59:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:59:35 INFO - Python 2.7.3 01:59:35 INFO - Return code: 0 01:59:35 INFO - grabbing minidump binary from tooltool 01:59:35 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:59:35 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 0x105cb1d30>, '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 0x105a16db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ca9b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:59:35 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 01:59:35 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 01:59:35 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 01:59:36 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:59:36 INFO - Return code: 0 01:59:36 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:59:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:59:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:59:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:59:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:59:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Yjn1i2tSRw6h0GFygfc2gQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0173', '--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 01:59:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Yjn1i2tSRw6h0GFygfc2gQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0173 --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 01:59:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V0fwv76H58/Render', 01:59:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:59:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:59:36 INFO - 'HOME': '/Users/cltbld', 01:59:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:59:36 INFO - 'LOGNAME': 'cltbld', 01:59:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:59:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:59:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:59:36 INFO - 'MOZ_NO_REMOTE': '1', 01:59:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:59:36 INFO - 'NO_EM_RESTART': '1', 01:59:36 INFO - 'PAGER': '/bin/cat', 01:59:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:59:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:59:36 INFO - 'PWD': '/builds/slave/test', 01:59:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:59:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:59:36 INFO - 'SHELL': '/bin/bash', 01:59:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IE2FZyiDpT/Listeners', 01:59:36 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 01:59:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:59:36 INFO - 'USER': 'cltbld', 01:59:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:59:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:59:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:59:36 INFO - 'XPC_FLAGS': '0x0', 01:59:36 INFO - 'XPC_SERVICE_NAME': '0', 01:59:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:59:36 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Yjn1i2tSRw6h0GFygfc2gQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0173', '--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 01:59:36 INFO - mozversion application_buildid: 20160715230551 01:59:36 INFO - mozversion application_changeset: c47d2ca42dff8772f2e86be895ff331346a37b6c 01:59:36 INFO - mozversion application_display_name: Nightly 01:59:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:59:36 INFO - mozversion application_name: Firefox 01:59:36 INFO - mozversion application_remotingname: firefox 01:59:36 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 01:59:36 INFO - mozversion application_vendor: Mozilla 01:59:36 INFO - mozversion application_version: 50.0a1 01:59:36 INFO - mozversion platform_buildid: 20160715230551 01:59:36 INFO - mozversion platform_changeset: c47d2ca42dff8772f2e86be895ff331346a37b6c 01:59:36 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 01:59:36 INFO - mozversion platform_version: 50.0a1 01:59:36 INFO - using testdate: 1468659576 01:59:36 INFO - actual date: 1468659576 01:59:36 INFO - starting webserver on 'localhost:55810' 01:59:36 INFO - SUITE-START | Running 2 tests 01:59:36 INFO - TEST-START | dromaeo_css 01:59:36 INFO - operating with platform_type : mac_ 01:59:36 INFO - Initialising browser for dromaeo_css test... 01:59:36 INFO - TEST-INFO | started process 2227 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/tmpaJW0Fk/profile http://localhost:55810/getInfo.html) 01:59:38 INFO - PROCESS | 2227 | __metrics Screen width/height:1600/1200 01:59:38 INFO - PROCESS | 2227 | colorDepth:24 01:59:38 INFO - PROCESS | 2227 | Browser inner width/height: 1024/689 01:59:38 INFO - PROCESS | 2227 | __metrics 01:59:38 INFO - PROCESS | 2227 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 01:59:38 INFO - PROCESS | 2227 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:59:39 INFO - TEST-INFO | 2227: exit 0 01:59:39 INFO - Browser initialized. 01:59:39 INFO - Running cycle 1/1 for dromaeo_css test... 01:59:39 INFO - TEST-INFO | started process 2229 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/tmpaJW0Fk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:59:51 INFO - PROCESS | 2229 | RSS: Main: 243982336 01:59:51 INFO - PROCESS | 2229 | 02:00:43 INFO - PROCESS | 2229 | 1468659643018 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] 02:02:53 INFO - PROCESS | 2229 | Cycle 1(1): loaded http://localhost:55810/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55810/tests/dromaeo/cssquery-ext.html) 02:02:54 INFO - PROCESS | 2229 | RSS: Main: 375918592 02:02:54 INFO - PROCESS | 2229 | 02:05:58 INFO - PROCESS | 2229 | Cycle 1(1): loaded http://localhost:55810/tests/dromaeo/cssquery-ext.html (next: http://localhost:55810/tests/dromaeo/cssquery-jquery.html) 02:05:58 INFO - PROCESS | 2229 | RSS: Main: 381173760 02:05:58 INFO - PROCESS | 2229 | 02:09:00 INFO - PROCESS | 2229 | Cycle 1(1): loaded http://localhost:55810/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55810/tests/dromaeo/cssquery-mootools.html) 02:09:01 INFO - PROCESS | 2229 | RSS: Main: 371355648 02:09:01 INFO - PROCESS | 2229 | 02:12:03 INFO - PROCESS | 2229 | Cycle 1(1): loaded http://localhost:55810/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55810/tests/dromaeo/cssquery-prototype.html) 02:12:04 INFO - PROCESS | 2229 | RSS: Main: 369999872 02:12:04 INFO - PROCESS | 2229 | 02:15:08 INFO - PROCESS | 2229 | Cycle 1(1): loaded http://localhost:55810/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55810/tests/dromaeo/cssquery-yui.html) 02:15:08 INFO - PROCESS | 2229 | RSS: Main: 336343040 02:15:08 INFO - PROCESS | 2229 | 02:18:12 INFO - PROCESS | 2229 | Cycle 1(1): loaded http://localhost:55810/tests/dromaeo/cssquery-yui.html (next: http://localhost:55810/tests/dromaeo/cssquery-dojo.html) 02:18:12 INFO - PROCESS | 2229 | RSS: Main: 381521920 02:18:12 INFO - PROCESS | 2229 | 02:18:12 INFO - PROCESS | 2229 | __start_tp_report 02:18:12 INFO - PROCESS | 2229 | _x_x_mozilla_page_load 02:18:12 INFO - PROCESS | 2229 | _x_x_mozilla_page_load_details 02:18:12 INFO - PROCESS | 2229 | |i|pagename|runs| 02:18:12 INFO - PROCESS | 2229 | |0;dojo.html;3157.6368717597475;3313.933721325572;3322.9003129906105;3370.5955285365744;3376.9831150844243;5506.201600767887;5577.358603760569;5577.8047768328115;5662.801801936933;5706.65760054397;5120.359955005624;5573.829495805886;5628.915566266509;5657.3494048184475;5779.95960727069;5079.720615366154;5080.339555857733;5167.431582525919;5168.508991645787;5271.488129415587;5237.6071794615555;5264.447233040533;5277.076511610472;5277.076511610472;5285.920711189336;5098.362704661918;5132.538071573576;5134.64057515974;5189.506996835295;5219.739013049332;5137.845864624067;5166.948330516684;5174.939137476808;5254.343207099113;5284.445133260991;4214.325707886724;5142.408623008365;5171.974140129313;5211.2443695663915;5227.8170264040955;5517.641353312062;5616.775328986836;5704.1443783432405;5716.685582292972;5784.479396854304;6170.784022559234;6187.597806142587;6257.624542527463;6268.310485846553;6271.6864156792435;6250.531210159256;6299.811005669837;6316.968415157895;6329.68351582419;6335.968320158416;5617.21359009739;5666.46168613981;5680.318361796611;5823.272090988627;5839.795607153729;6271.498280137578;6279.968600157062;6302.936970630235;6331.525135614847;6353.809385718436;6120.449159575571;6120.510359171256;6136.6624835633575;6138.478229350466;6139.4781443577795;6206.596571222946;6214.8135555933395;6244.406781355764;6262.499000079983;6286.057091436322;5954.285485741739;6006.069059295816;6101.03536932808;6184.134221209034;6186.659733714599;6262.843428914222;6264.467520260829;6273.0904018917;6274.435300822948;6286.4342209201395;4957.851264462072;5001.44984051754;5005.449400565934;5007.399112106497;5046.772895219724;4393.0774537347515;4422.071365013383;4474.284114541722;4489.259272220047;4510.120526497367;5580.190872323572;5614.550835933115;5619.438056194348;5642.097264437671;5643.661380317194;5500.5973476763165;5969.701514924236;6117.736059405574;6190.50475961922;6194.473469755031;5620.100783874561;5634.746436410372;5653.208550802891;5657.915131273035;5691.146328050825;5297.099493086184;5384.6499977501335;5404.594655400782;5412.539934105566;5417.756200970887;5276.498732620395;6231.594946328474;6271.749130034747;6311.747530098744;6357.078223657636;6296.716647750954;6305.621662700252;6305.653189074645;6333.746650134128;6336.683165841598;6349.174607301022;6357.523185761179;6358.491320694426;6358.904616430665;6359.49124070064;5915.91126133117;6105.572609917263;6113.385090542009;6118.694065296629;6174.8147555573405;6246.281677606951;6250.593711408744;6262.53031022666;6274.498040156868;6278.748850045947;6124.295705993868;6125;6128.540359472968;6135.6932153393045;6136.23297087864;4929.383827021622;5788.957987562279;5834.03738383162;5887.234659494238;5932.851678708067;5769.451902069297;5770.278715160605;5776.277965254343;5786.971065144816;5793.681347525927;5875.3243377012195;5888.735006924613;5896.55775816824;5898.233229680114;5965.105234214902;5714.342850572238;5879.676617786063;5917.792877249276;5934.910976335272;5964.075568286922 02:18:12 INFO - PROCESS | 2229 | |1;ext.html;469.3148003914285;515.6493584362634;518.6499113098658;521.8512723873697;539.6788910598195;32170.356592868127;41094.38358424626;42448.36327455083;42555.936101597435;42589;43764.562354376445;44040.77979610106;44054.89862753433;44135.33796993048;44331.89170270745;7212.747553835617;9634.518274086302;9818.165455936254;9820;9826.115649591535;8820.117988201193;11866.7626647467;11975.580854670088;11993.760124797498;12378.319192444413;12039.63881083569;12049.337286449241;12120.212186207913;12127.818082728721;12153.453094610764;8767.824643507094;9829.655962041294;11198.880111988736;12015.519379224865;12124.757504849898;11873.821892671616;11949.64151075469;11954;11966.82049769254;11978;40277.597224027675;40489.19021619551;40548.79725601353;40582.18835623271;40607.18785624271;3711.870084547028;3805.8667946621736;3833.5016447861876;3915.549711783153;4335.5014173370155;31030.689693103006;31366.52950205749;32279.193020174447;32788.67211327904;32866.50700239498;3301.570795796555;3307.520409540627;3348.430766769655;3386.0857568456627;3433.1073920780777;489128;490131.0986890121;490473;495919;497258;57667.711661441426;58628.534286643175;58730.23809285728;58748;58857;56044;56070.15894761582;56103;56144.43855561474;56225;16346.509604711877;16608.418705345495;16611.83388166127;16616.750748738777;16649.91675041617;11750.882491174978;11968.401579920968;12808.231506109663;12930.482780688855;13022.95816334691;15286.92991490585;15293.694126117416;15295.235238238043;15324.080555166725;15479.303431345617;5336.866578335573;5373.205932914862;6407.743690252338;6469.708863101172;6606.8017959461295;916.6333466613409;948.127722495306;989.8020395920845;995.865558149655;1096.7970925378831;26899;31627.36745265082;31721.61464304609;32017.19957001047;32133.463441696633;1084.0839490630406;1118.8657361116718;1127.8590176227972;1148.9827652585218;1227.049036996335;15579.84558717249;16390.180490975403;16472.25874835635;16560.50318490423;16880.746788797933;647245;648963;652665;652977;654542.5455854498;58556.70721646365;58575;58618;58719.706401467716;58993;1269.4097244781221;1384.0795870746042;1408.0917808013774;1424.3376829774204;1434.168182454174;31416.057518274487;31857.522137168424;31877.20306992344;32613.021609351756;32710;51683.224751628004;52247;52370.476295238084;52386.21420678769;52462.47537524576;526970;528814;530123;533259;533825;40694;41204.79397602993;42046.78976605098;42184.789076054425;42273;48286.5171348282;48567.27149092837;49232.261516078004;49434.752826235635;49931;39962.400563992145;40030;40483.79758101191;41902.37146442745;42795.14409711851;39414.40878386769;40807.34894552907;41150;41150.58849411467;41270.9682257934;39869.60130398775;40185.79442616726;40433;41143;41315.7934210327;34396.82801585976;34968.9509314721;35559.93320200398;35589.644103559665;35836.28327433489;25787.484250314512;25859.224223273333;26403.471930560896;26410.86794566015;26434.339141521617 02:18:12 INFO - PROCESS | 2229 | |2;jquery.html;359.92981368633093;372.36140019865934;387.3763241181697;391.4148348219412;409.9852864161201;16095.839041609595;16102.758458623115;21466.677999830015;21716.80557569333;21872.1251149954;20553.691694624555;20938;21271.596544552875;21764.911754412296;21827.890860545715;5124.718140502271;5727.856803579913;5964;5988.401159883999;5988.700564971756;5821.243238379005;5952.19645347879;5964.314103878044;5999.250093738283;6004.0693692477735;5606.915896261559;5747.936631723123;5756.395578464264;5772.480476757091;5845.357010728838;5101.464346243628;5843.620164689303;5877.413098463412;5889.381614930414;5897.7346019429015;5091.694498330112;5123;5123.410807757081;5125.103106956305;5127.743612819381;26543.20370388872;26600.467990640078;26618.669066546594;26942.46115077707;27025.72974270252;2437.4759426723163;2939.235798692334;2972.390659914712;3139.136737397234;3177.8887738929257;15757.527274181793;15801.288941997638;15940.442084526985;16131.032138071754;16336.591585210346;1721.5523963769385;1766.5053784940237;1809.8918926964345;1829.5461299205563;1952.453313072342;120085;124236;124770.75229247683;124842.37578812138;125858.11212831814;10778.568857245778;11161.818881618861;11174.553017879356;11220;11347.886521134684;13900.582982510541;14054.718905622034;14084.295785210903;14089.43642254319;14104.08323458972;8790.164934331227;8829.293656507463;8871.556422178994;8888;8995.505247211335;8855.557222138867;8857.557122143868;8877.778055548662;8994.640214391482;9093.818123637622;6651.235107962646;6790.185177778698;6791.796246112624;6857.451403887677;6929.688164032631;1398.5245016694373;1676.9100084944882;1834.8807327523668;1873.381784011273;1875.9531011724816;348.4546684438845;383.86372837642796;880.8942926848691;911.0616065452595;964.8215080210043;11602.303861768321;12029.639110826689;12145.210561313486;12216.389180540937;12217;424.9745015299092;444.822071171534;447.16157205240177;450.1671906972141;450.54044874228106;6064.818055458343;6124.418180272867;6156.938430615726;6240.375962403814;6264.342244064308;601589;603140.8743650204;612846;616540.9172954106;616888;94264.58603120959;127398.08902866465;127662;150445;152793;435.4513313225359;443.4833419066816;446.9039156581351;449.57964303376446;452.0100978856294;11132.666020019413;11275.379854107774;11353.886461135284;11452.08383329315;11467.025302849352;56639.71680141573;57245.42754572401;59122.704386477795;59217.70391148017;59404.70297648657;86918.130818691;87543.1245687535;87732.56133719545;87759.97920166244;89579;7787.221277872394;7916.802079948047;7921.603919804102;7960.164182760958;8205.712800052208;9015.504147271962;12066;12267.57063502773;12347.62957111288;12579.245245285312;10574.524146413276;10844.891551084389;10965.232433729563;11023.393713345842;11097.944510277397;10355.17158627323;10507.94746026265;10675.199360047873;10838.645169353831;10847.891521084688;11087.889994787594;11180;11240.381779001906;11303.943480282545;11329.376884271443;6875.48433867472;6876.17485901695;7319.268073192638;7377.594232317274;8224.712135075244;9304.413760076828;11086.501107450023;11121.388323642277;11218.887811122111;11272.154588405738 02:18:12 INFO - PROCESS | 2229 | |3;mootools.html;1544.2741911301687;1583.4220509514028;1780.8842425242358;1901.9904900475512;1911.6176764647066;8580.313574914007;8851.159139881702;9027.954860225705;9054.275657947364;9059.682911098113;7844.372450203984;7994.4803587766755;8004.719834805783;8157.102718700939;8798.648054077845;2132.8720276783365;2160.470684682255;2284.3489605462446;2356.8703721295324;2359.044586942291;2361.2680069178523;2383.797377222938;2384.7376788553333;2385.33210701004;2385.5825230584664;2177.7151480626426;2181.4982554012586;2367.396313939943;2368.8933997970134;2370.4547953970596;2086.488810241485;2156.892155392224;2160.384290477214;2160.967585486219;2293.839431239814;2354.8233882458885;2389.7371289158177;2390.6294524348755;2391.158807718065;2392.940176495584;9029.322800789967;9084.364094513387;9090.363674542785;9092.408993415407;9095.226905713022;5246.921296180561;5414.8917021659345;5418.268533747933;5423.5932305076885;5440.972795136038;3493.7030352420074;3512.5784905811465;3570.3394871948512;3614.078410005458;3615.7107431405425;1072.6460268111505;1078.946052697362;1082.9729256768564;1090.596479302663;1103.7957977774058;11983.760324793542;12021.699457513545;12038.157328986887;12044.879551204463;12066.09504287182;4592.954070459319;4630.86107416778;4725.787339569728;4794.7842347094465;4795.808167673254;10092.848607270897;10093.899061009295;10096.646617368357;10113.494325283706;10140.13808826258;5394.946050539523;5396.811111611153;5399.217113518518;5400.567954563626;5410.296661433988;4767.189577771718;4929;4930.260460930817;4951.702897826142;4988.575971042502;3442.896713098611;3538.5930617978747;3834.386498160281;4011.7592944422845;4020.6582440492884;1126.341090462089;1286.1061562214359;1289.3231053696697;1311.6786387334973;1321.7951217561101;839.7816567692322;950.5627411390838;960.2270172511196;973.7368139806362;1001.5342865567546;5910.468057874726;5926.318473375617;5965.701714914237;6136.570440069152;6151.415615516519;948.9051094890456;952.2334520710895;965.9082387173207;977.4623956823718;1003.2124782046059;4012.137390461065;4064.1262128642484;4067.3695577185586;4141.275276826604;4141.64795992338;8792.8681069784;10492;10905.236633435583;10964.671059868217;11008.724781880357;10930.56277748897;10949.124070074375;10974.122070234362;10977.451127443595;10978.890211097787;7832;8229.05365882919;8392.034915984617;8415.074341822394;8431.451955622864;6077.4834139098675;6079;6195.194624698941;6196.256449226122;6235.812925612239;10213.795724085625;10257.948710256402;10350.637727679496;10365.170786337225;10366.792664146524;9916.702498925044;10095.545700443645;10528.263021588415;10537.57849686004;10616;6959.930400695928;7626.923730762622;7807.180246074307;7812.492188007761;7825.256600622932;7517.736879209201;7841.647125879235;7946.92053079485;8035.035795704319;8037.799055023671;7670;7673.731419400294;7718.189590092959;7800.102988156208;7872.448928574945;7206.4595155364605;7505.587192704454;7525.36034437079;7603;7606.695732170651;6450.870982580416;6991.2309645937885;7033.472489563405;7081.716731330689;7099.893501597377;4199.223143718422;5071.898562028665;5119.104156772475;5167.379914410295;5182.6113041521285;5144.331236939323;5220.947790522198;5259.89480210386;5301.840944771663;5387.434319396563 02:18:12 INFO - PROCESS | 2229 | |4;prototype.html;301.74954787526354;305.42427524117033;308.91350421881873;314.4716875648909;317.1231544778686;4335.393044973703;5322.228276899852;5351.839444816662;5387.973060134699;5391.757370918308;5187.6628019178725;5206.661566998152;5263.105272103742;5281.419043905177;5312.893742125155;3295;3617.503922293609;3682.1773222254888;3830.8467661293516;3961.7028722845757;3530.523379343794;3797.2215695782297;3930.2139572085553;4044.1507283470505;4106.618161466357;3453.513470301379;3623.804144632278;3957.9010524736823;3970.702197335197;3995.5205375354853;3108.518179682153;3578.6600272974156;3842.269968705937;3932.6460618544183;4012.799360032016;2897.9710202897913;3268.1176082457605;3482.703970162539;3526.2594855080206;3552.8401221944814;3867.5552311484257;4680.7191568506;4757.976210118962;5142;5149.356330458692;4215.346621273708;4578.748168850712;4595.149897269016;4621.8739379884955;4691.577758001797;5078.7968481260705;5556.527695145917;5606.411326810708;5630.5495560355075;5639.548836093103;3843.8078096095364;3854.383298672199;3863.3818589025623;3885.572587015426;3911.6283953024704;5431.158170483542;5818.76724930998;5963.52291816658;5975.880482390371;5977.850553736148;3440.552728145375;3852.653261206505;3861.942070868939;3865.729398942103;3869.9613003870163;3946.7039972002217;3960.7821569813937;3961.346377847623;3964.4647972523917;3968.543617483969;3680.392735198716;4116.958830411718;4598.5970381917805;4625.583697467244;4632.2125238709505;4954.281629163823;5301.628885978022;5553.583481238923;5590.888182236333;5600.383957764642;1174.8369114576678;1249.775040492702;1335.8998075144402;1339.638297659646;1341.6914109754616;737.4174402222292;744.9515781474187;775.337086790797;780.4614815777096;817.8509194581623;3742.4760533525327;4198.6011328923705;4613.723176609415;5073.898522029539;5147.6911385317;5133.974330128325;5344.35867695879;5470.64440811346;5485.9725701371235;5643.351014633287;4273.764942928106;5157.742112894341;5170.844874653766;5183.444966510107;5242.13504771716;2350.8354415190774;2506.4611108611734;2804.551271796503;2951.188423183599;3014.668386477486;4557.897891897319;5324.92012619811;5657.745401456944;5888.114823736339;5914.8817023659285;4599.0342028174455;5143.717095559706;5408.486193811582;5600.515863296229;5746;5122.974385128051;5895.263092113486;5898.522620499486;5913.556483263773;6001.1898393717165;4767.736549814233;5728.1694154348215;5735.7705691773535;5736.110902810071;5832.591718579659;5527.39198688152;5538.196961440488;5719.8856022880145;5749.511291540266;5757.510611598061;5099.87250318745;5513.558915286848;5615.943840561542;5875.294964604275;6005.849853753691;3802.94295585572;3869.458275841328;3886.9222615548097;3893.552241492265;3915.667168290727;4821.661494175392;5026.623003274696;5446.754896029609;5526.292116862789;5779.422057794187;4800.4479484859685;5524.486358706859;5532.529734972572;5532.612717109763;5548.361938377138;5048.873778155428;5501.862453438696;5534.363548192009;5600.607957443103;5639.774409023593;5156.56169225605;5157.742112894415;5212.611802328517;5529.253550770755;5551.916721249104;4689.577937985598;5023.397192336943;5038.924416133834;5401.432849550898;5590.301212348457;4911.189653707098;5304.939012197653;5326.893462130658;5382.138857782736;5420.9186862197885 02:18:12 INFO - PROCESS | 2229 | |5;yui.html;734.9669264883078;740.8555331710319;775.0621747685301;809.279741030483;831.2601784411871;15143.394264229431;16478.7528187077;16503.75244371334;16510.174491275448;16529;15067.246637668128;15243.771343429857;15262.542123736277;15270;15335.233238338094;721.9855602887939;741.2550386861197;882.4793371910572;890.4924193209873;903.5346796399859;895.9148880856341;904.7873749668855;909.7498187998287;909.895362033368;910.1171863292595;944.4805357053627;946.5693109635099;946.7065209784988;947.5735918836517;947.7535840681403;747.5141158247128;920.5029284186531;941.9058094190594;955.1165172215673;958.9472579008152;706.3889735378912;706.6502081469705;706.780897921646;707.7876637008928;709.5919846088468;15269;15298.311575979107;15332.693346133015;15333.463328783551;15335.616609584738;14259.78610320846;14405.279736013263;14424.855751442456;14436.566902992927;14442.566722998326;13103.344832758325;13138.540151094665;13238.933805331008;13343.332833358294;13468.326583670876;4621.884452888671;4670.883227919295;4696.342512048316;4723.4568024677255;4835.516448355172;102891;113505.43247283793;115256.42371788171;116200;117180.24229636477;11493.080553555696;11565.479553420117;11575.016123629417;11777.116716246315;12100.757984840255;18346.541336466697;22731.431714206945;22764.658530122062;22862.08551657915;22932;7958.840823183504;8024.117347091816;8111.148329425326;8133.4306598538715;8182.795430114175;4370.694051416371;4378.890527736832;4418.955810441855;4430.5126436092005;4616.746078965689;3576.624454432299;3669.908252293714;3701.6668499835146;3703.2593481303306;3708.8887333380044;687.2371667449128;687.8796210663214;694.5797792335666;694.7776711452387;696.7352406085756;552.5386302437429;591.6982339007051;601.2484394506866;601.6841158391792;601.7653115285042;9199.494027828534;9200.723978280663;9243.445393276425;9311.48786816718;9324.953375233215;608.9177960975212;636.2651137935692;636.6375955455329;640.3564417760122;640.7148818775694;3909.5730058529;4341.761203133858;4385.210662080793;4423.203823311835;4496.797644106023;117331;129054.35472822764;141908.2904585491;142357;145853.27073364778;112274;117145;117483;119813;122115;77187;78856.21143788603;79982.6000869992;80145.5956321323;115546;7862.33170180541;9778.462184579916;9825;9836.409815411098;9930.900690992998;54708.72645636746;60057;65656.67171664111;65668.34331656623;66553.33616659623;94099;101353.49323253632;102780;104230;104264;13691.178529288274;13742;13828.516001939885;13888.86111138916;14252.501162459264;13412.396442160321;15431.537053888402;15536.61158471002;15751.44869929547;16473.176341182654;12838.16551924122;14395;14870.628234294229;14886;14891.032082914577;14031.438742450187;14132;14408.783868241775;15033.699326013637;15051.476726159734;13462;13616.251106188858;13953.302334883418;14204;14349.660066498107;9711.36876103051;11431.028362589273;11490.255526161862;11585.246958947642;12154.817677735018;11634.709132271422;11738.712871780006;11752.294862308288;11854.348010859485;11889.405529723306 02:18:12 INFO - PROCESS | 2229 | __end_tp_report 02:18:12 INFO - PROCESS | 2229 | __start_cc_report 02:18:12 INFO - PROCESS | 2229 | _x_x_mozilla_cycle_collect,1297 02:18:12 INFO - PROCESS | 2229 | __end_cc_report 02:18:12 INFO - PROCESS | 2229 | __startTimestamp1468660692304__endTimestamp 02:18:12 INFO - PROCESS | 2229 | 02:18:12 INFO - PROCESS | 2229 | ------- Summary: start ------- 02:18:12 INFO - PROCESS | 2229 | Number of tests: 6 02:18:12 INFO - PROCESS | 2229 | 02:18:12 INFO - PROCESS | 2229 | [#0] dojo.html Cycles:165 Average:5691.05 Median:5793.68 stddev:649.25 (11.2%) stddev-sans-first:620.08 02:18:12 INFO - PROCESS | 2229 | Values: 3157.6 3313.9 3322.9 3370.6 3377.0 5506.2 5577.4 5577.8 5662.8 5706.7 5120.4 5573.8 5628.9 5657.3 5780.0 5079.7 5080.3 5167.4 5168.5 5271.5 5237.6 5264.4 5277.1 5277.1 5285.9 5098.4 5132.5 5134.6 5189.5 5219.7 5137.8 5166.9 5174.9 5254.3 5284.4 4214.3 5142.4 5172.0 5211.2 5227.8 5517.6 5616.8 5704.1 5716.7 5784.5 6170.8 6187.6 6257.6 6268.3 6271.7 6250.5 6299.8 6317.0 6329.7 6336.0 5617.2 5666.5 5680.3 5823.3 5839.8 6271.5 6280.0 6302.9 6331.5 6353.8 6120.4 6120.5 6136.7 6138.5 6139.5 6206.6 6214.8 6244.4 6262.5 6286.1 5954.3 6006.1 6101.0 6184.1 6186.7 6262.8 6264.5 6273.1 6274.4 6286.4 4957.9 5001.4 5005.4 5007.4 5046.8 4393.1 4422.1 4474.3 4489.3 4510.1 5580.2 5614.6 5619.4 5642.1 5643.7 5500.6 5969.7 6117.7 6190.5 6194.5 5620.1 5634.7 5653.2 5657.9 5691.1 5297.1 5384.6 5404.6 5412.5 5417.8 5276.5 6231.6 6271.7 6311.7 6357.1 6296.7 6305.6 6305.7 6333.7 6336.7 6349.2 6357.5 6358.5 6358.9 6359.5 5915.9 6105.6 6113.4 6118.7 6174.8 6246.3 6250.6 6262.5 6274.5 6278.7 6124.3 6125.0 6128.5 6135.7 6136.2 4929.4 5789.0 5834.0 5887.2 5932.9 5769.5 5770.3 5776.3 5787.0 5793.7 5875.3 5888.7 5896.6 5898.2 5965.1 5714.3 5879.7 5917.8 5934.9 5964.1 02:18:12 INFO - PROCESS | 2229 | 02:18:12 INFO - PROCESS | 2229 | [#1] ext.html Cycles:180 Average:70222.88 Median:40486.49 stddev:149907.98 (370.3%) stddev-sans-first:150237.03 02:18:12 INFO - PROCESS | 2229 | Values: 469.3 515.6 518.6 521.9 539.7 32170.4 41094.4 42448.4 42555.9 42589.0 43764.6 44040.8 44054.9 44135.3 44331.9 7212.7 9634.5 9818.2 9820.0 9826.1 8820.1 11866.8 11975.6 11993.8 12378.3 12039.6 12049.3 12120.2 12127.8 12153.5 8767.8 9829.7 11198.9 12015.5 12124.8 11873.8 11949.6 11954.0 11966.8 11978.0 40277.6 40489.2 40548.8 40582.2 40607.2 3711.9 3805.9 3833.5 3915.5 4335.5 31030.7 31366.5 32279.2 32788.7 32866.5 3301.6 3307.5 3348.4 3386.1 3433.1 489128.0 490131.1 490473.0 495919.0 497258.0 57667.7 58628.5 58730.2 58748.0 58857.0 56044.0 56070.2 56103.0 56144.4 56225.0 16346.5 16608.4 16611.8 16616.8 16649.9 11750.9 11968.4 12808.2 12930.5 13023.0 15286.9 15293.7 15295.2 15324.1 15479.3 5336.9 5373.2 6407.7 6469.7 6606.8 916.6 948.1 989.8 995.9 1096.8 26899.0 31627.4 31721.6 32017.2 32133.5 1084.1 1118.9 1127.9 1149.0 1227.0 15579.8 16390.2 16472.3 16560.5 16880.7 647245.0 648963.0 652665.0 652977.0 654542.5 58556.7 58575.0 58618.0 58719.7 58993.0 1269.4 1384.1 1408.1 1424.3 1434.2 31416.1 31857.5 31877.2 32613.0 32710.0 51683.2 52247.0 52370.5 52386.2 52462.5 526970.0 528814.0 530123.0 533259.0 533825.0 40694.0 41204.8 42046.8 42184.8 42273.0 48286.5 48567.3 49232.3 49434.8 49931.0 39962.4 40030.0 40483.8 41902.4 42795.1 39414.4 40807.3 41150.0 41150.6 41271.0 39869.6 40185.8 40433.0 41143.0 41315.8 34396.8 34969.0 35559.9 35589.6 35836.3 25787.5 25859.2 26403.5 26410.9 26434.3 02:18:12 INFO - PROCESS | 2229 | 02:18:12 INFO - PROCESS | 2229 | [#2] jquery.html Cycles:180 Average:35582.81 Median:385.62 stddev:102257.01 (26517.6%) stddev-sans-first:102509.66 02:18:12 INFO - PROCESS | 2229 | Values: 359.9 372.4 387.4 391.4 410.0 16095.8 16102.8 21466.7 21716.8 21872.1 20553.7 20938.0 21271.6 21764.9 21827.9 5124.7 5727.9 5964.0 5988.4 5988.7 5821.2 5952.2 5964.3 5999.3 6004.1 5606.9 5747.9 5756.4 5772.5 5845.4 5101.5 5843.6 5877.4 5889.4 5897.7 5091.7 5123.0 5123.4 5125.1 5127.7 26543.2 26600.5 26618.7 26942.5 27025.7 2437.5 2939.2 2972.4 3139.1 3177.9 15757.5 15801.3 15940.4 16131.0 16336.6 1721.6 1766.5 1809.9 1829.5 1952.5 120085.0 124236.0 124770.8 124842.4 125858.1 10778.6 11161.8 11174.6 11220.0 11347.9 13900.6 14054.7 14084.3 14089.4 14104.1 8790.2 8829.3 8871.6 8888.0 8995.5 8855.6 8857.6 8877.8 8994.6 9093.8 6651.2 6790.2 6791.8 6857.5 6929.7 1398.5 1676.9 1834.9 1873.4 1876.0 348.5 383.9 880.9 911.1 964.8 11602.3 12029.6 12145.2 12216.4 12217.0 425.0 444.8 447.2 450.2 450.5 6064.8 6124.4 6156.9 6240.4 6264.3 601589.0 603140.9 612846.0 616540.9 616888.0 94264.6 127398.1 127662.0 150445.0 152793.0 435.5 443.5 446.9 449.6 452.0 11132.7 11275.4 11353.9 11452.1 11467.0 56639.7 57245.4 59122.7 59217.7 59404.7 86918.1 87543.1 87732.6 87760.0 89579.0 7787.2 7916.8 7921.6 7960.2 8205.7 9015.5 12066.0 12267.6 12347.6 12579.2 10574.5 10844.9 10965.2 11023.4 11097.9 10355.2 10507.9 10675.2 10838.6 10847.9 11087.9 11180.0 11240.4 11303.9 11329.4 6875.5 6876.2 7319.3 7377.6 8224.7 9304.4 11086.5 11121.4 11218.9 11272.2 02:18:12 INFO - PROCESS | 2229 | 02:18:12 INFO - PROCESS | 2229 | [#3] mootools.html Cycles:180 Average:5776.61 Median:4795.30 stddev:3251.82 (67.8%) stddev-sans-first:3245.39 02:18:12 INFO - PROCESS | 2229 | Values: 1544.3 1583.4 1780.9 1902.0 1911.6 8580.3 8851.2 9028.0 9054.3 9059.7 7844.4 7994.5 8004.7 8157.1 8798.6 2132.9 2160.5 2284.3 2356.9 2359.0 2361.3 2383.8 2384.7 2385.3 2385.6 2177.7 2181.5 2367.4 2368.9 2370.5 2086.5 2156.9 2160.4 2161.0 2293.8 2354.8 2389.7 2390.6 2391.2 2392.9 9029.3 9084.4 9090.4 9092.4 9095.2 5246.9 5414.9 5418.3 5423.6 5441.0 3493.7 3512.6 3570.3 3614.1 3615.7 1072.6 1078.9 1083.0 1090.6 1103.8 11983.8 12021.7 12038.2 12044.9 12066.1 4593.0 4630.9 4725.8 4794.8 4795.8 10092.8 10093.9 10096.6 10113.5 10140.1 5394.9 5396.8 5399.2 5400.6 5410.3 4767.2 4929.0 4930.3 4951.7 4988.6 3442.9 3538.6 3834.4 4011.8 4020.7 1126.3 1286.1 1289.3 1311.7 1321.8 839.8 950.6 960.2 973.7 1001.5 5910.5 5926.3 5965.7 6136.6 6151.4 948.9 952.2 965.9 977.5 1003.2 4012.1 4064.1 4067.4 4141.3 4141.6 8792.9 10492.0 10905.2 10964.7 11008.7 10930.6 10949.1 10974.1 10977.5 10978.9 7832.0 8229.1 8392.0 8415.1 8431.5 6077.5 6079.0 6195.2 6196.3 6235.8 10213.8 10257.9 10350.6 10365.2 10366.8 9916.7 10095.5 10528.3 10537.6 10616.0 6959.9 7626.9 7807.2 7812.5 7825.3 7517.7 7841.6 7946.9 8035.0 8037.8 7670.0 7673.7 7718.2 7800.1 7872.4 7206.5 7505.6 7525.4 7603.0 7606.7 6450.9 6991.2 7033.5 7081.7 7099.9 4199.2 5071.9 5119.1 5167.4 5182.6 5144.3 5220.9 5259.9 5301.8 5387.4 02:18:12 INFO - PROCESS | 2229 | 02:18:12 INFO - PROCESS | 2229 | [#4] prototype.html Cycles:180 Average:4443.83 Median:5076.35 stddev:1393.01 (27.4%) stddev-sans-first:1361.79 02:18:12 INFO - PROCESS | 2229 | Values: 301.7 305.4 308.9 314.5 317.1 4335.4 5322.2 5351.8 5388.0 5391.8 5187.7 5206.7 5263.1 5281.4 5312.9 3295.0 3617.5 3682.2 3830.8 3961.7 3530.5 3797.2 3930.2 4044.2 4106.6 3453.5 3623.8 3957.9 3970.7 3995.5 3108.5 3578.7 3842.3 3932.6 4012.8 2898.0 3268.1 3482.7 3526.3 3552.8 3867.6 4680.7 4758.0 5142.0 5149.4 4215.3 4578.7 4595.1 4621.9 4691.6 5078.8 5556.5 5606.4 5630.5 5639.5 3843.8 3854.4 3863.4 3885.6 3911.6 5431.2 5818.8 5963.5 5975.9 5977.9 3440.6 3852.7 3861.9 3865.7 3870.0 3946.7 3960.8 3961.3 3964.5 3968.5 3680.4 4117.0 4598.6 4625.6 4632.2 4954.3 5301.6 5553.6 5590.9 5600.4 1174.8 1249.8 1335.9 1339.6 1341.7 737.4 745.0 775.3 780.5 817.9 3742.5 4198.6 4613.7 5073.9 5147.7 5134.0 5344.4 5470.6 5486.0 5643.4 4273.8 5157.7 5170.8 5183.4 5242.1 2350.8 2506.5 2804.6 2951.2 3014.7 4557.9 5324.9 5657.7 5888.1 5914.9 4599.0 5143.7 5408.5 5600.5 5746.0 5123.0 5895.3 5898.5 5913.6 6001.2 4767.7 5728.2 5735.8 5736.1 5832.6 5527.4 5538.2 5719.9 5749.5 5757.5 5099.9 5513.6 5615.9 5875.3 6005.8 3802.9 3869.5 3886.9 3893.6 3915.7 4821.7 5026.6 5446.8 5526.3 5779.4 4800.4 5524.5 5532.5 5532.6 5548.4 5048.9 5501.9 5534.4 5600.6 5639.8 5156.6 5157.7 5212.6 5529.3 5551.9 4689.6 5023.4 5038.9 5401.4 5590.3 4911.2 5304.9 5326.9 5382.1 5420.9 02:18:12 INFO - PROCESS | 2229 | 02:18:12 INFO - PROCESS | 2229 | [#5] yui.html Cycles:180 Average:24176.03 Median:3685.79 stddev:37110.34 (1006.8%) stddev-sans-first:37172.70 02:18:12 INFO - PROCESS | 2229 | Values: 735.0 740.9 775.1 809.3 831.3 15143.4 16478.8 16503.8 16510.2 16529.0 15067.2 15243.8 15262.5 15270.0 15335.2 722.0 741.3 882.5 890.5 903.5 895.9 904.8 909.7 909.9 910.1 944.5 946.6 946.7 947.6 947.8 747.5 920.5 941.9 955.1 958.9 706.4 706.7 706.8 707.8 709.6 15269.0 15298.3 15332.7 15333.5 15335.6 14259.8 14405.3 14424.9 14436.6 14442.6 13103.3 13138.5 13238.9 13343.3 13468.3 4621.9 4670.9 4696.3 4723.5 4835.5 102891.0 113505.4 115256.4 116200.0 117180.2 11493.1 11565.5 11575.0 11777.1 12100.8 18346.5 22731.4 22764.7 22862.1 22932.0 7958.8 8024.1 8111.1 8133.4 8182.8 4370.7 4378.9 4419.0 4430.5 4616.7 3576.6 3669.9 3701.7 3703.3 3708.9 687.2 687.9 694.6 694.8 696.7 552.5 591.7 601.2 601.7 601.8 9199.5 9200.7 9243.4 9311.5 9325.0 608.9 636.3 636.6 640.4 640.7 3909.6 4341.8 4385.2 4423.2 4496.8 117331.0 129054.4 141908.3 142357.0 145853.3 112274.0 117145.0 117483.0 119813.0 122115.0 77187.0 78856.2 79982.6 80145.6 115546.0 7862.3 9778.5 9825.0 9836.4 9930.9 54708.7 60057.0 65656.7 65668.3 66553.3 94099.0 101353.5 102780.0 104230.0 104264.0 13691.2 13742.0 13828.5 13888.9 14252.5 13412.4 15431.5 15536.6 15751.4 16473.2 12838.2 14395.0 14870.6 14886.0 14891.0 14031.4 14132.0 14408.8 15033.7 15051.5 13462.0 13616.3 13953.3 14204.0 14349.7 9711.4 11431.0 11490.3 11585.2 12154.8 11634.7 11738.7 11752.3 11854.3 11889.4 02:18:12 INFO - PROCESS | 2229 | -------- Summary: end -------- 02:18:12 INFO - PROCESS | 2229 | 02:18:12 INFO - TEST-INFO | 2229: exit 0 02:18:12 INFO - TEST-OK | dromaeo_css | took 1116611ms 02:18:12 INFO - TEST-START | kraken 02:18:12 INFO - operating with platform_type : mac_ 02:18:12 INFO - Initialising browser for kraken test... 02:18:12 INFO - TEST-INFO | started process 2233 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/tmpLfWgQ8/profile http://localhost:55810/getInfo.html) 02:18:15 INFO - PROCESS | 2233 | __metrics Screen width/height:1600/1200 02:18:15 INFO - PROCESS | 2233 | colorDepth:24 02:18:15 INFO - PROCESS | 2233 | Browser inner width/height: 1024/689 02:18:15 INFO - PROCESS | 2233 | __metrics 02:18:15 INFO - PROCESS | 2233 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:18:15 INFO - PROCESS | 2233 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:18:16 INFO - TEST-INFO | 2233: exit 0 02:18:16 INFO - Browser initialized. 02:18:16 INFO - Running cycle 1/1 for kraken test... 02:18:16 INFO - TEST-INFO | started process 2234 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/tmpLfWgQ8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:18:28 INFO - PROCESS | 2234 | RSS: Main: 243654656 02:18:28 INFO - PROCESS | 2234 | 02:18:31 INFO - PROCESS | 2234 | Cycle 1(1): loaded http://localhost:55810/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55810/tests/kraken/driver.html?testName=audio-beat-detection) 02:18:31 INFO - PROCESS | 2234 | RSS: Main: 294756352 02:18:31 INFO - PROCESS | 2234 | 02:18:34 INFO - PROCESS | 2234 | Cycle 1(1): loaded http://localhost:55810/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55810/tests/kraken/driver.html?testName=audio-dft) 02:18:35 INFO - PROCESS | 2234 | RSS: Main: 304721920 02:18:35 INFO - PROCESS | 2234 | 02:18:38 INFO - PROCESS | 2234 | Cycle 1(1): loaded http://localhost:55810/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55810/tests/kraken/driver.html?testName=audio-fft) 02:18:39 INFO - PROCESS | 2234 | RSS: Main: 388530176 02:18:39 INFO - PROCESS | 2234 | 02:18:41 INFO - PROCESS | 2234 | Cycle 1(1): loaded http://localhost:55810/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55810/tests/kraken/driver.html?testName=audio-oscillator) 02:18:41 INFO - PROCESS | 2234 | RSS: Main: 290406400 02:18:41 INFO - PROCESS | 2234 | 02:18:44 INFO - PROCESS | 2234 | Cycle 1(1): loaded http://localhost:55810/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55810/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:18:44 INFO - PROCESS | 2234 | RSS: Main: 294596608 02:18:44 INFO - PROCESS | 2234 | 02:18:47 INFO - PROCESS | 2234 | Cycle 1(1): loaded http://localhost:55810/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55810/tests/kraken/driver.html?testName=imaging-darkroom) 02:18:48 INFO - PROCESS | 2234 | RSS: Main: 331788288 02:18:48 INFO - PROCESS | 2234 | 02:18:51 INFO - PROCESS | 2234 | Cycle 1(1): loaded http://localhost:55810/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55810/tests/kraken/driver.html?testName=imaging-desaturate) 02:18:51 INFO - PROCESS | 2234 | RSS: Main: 333656064 02:18:51 INFO - PROCESS | 2234 | 02:18:54 INFO - PROCESS | 2234 | Cycle 1(1): loaded http://localhost:55810/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55810/tests/kraken/driver.html?testName=json-parse-financial) 02:18:55 INFO - PROCESS | 2234 | RSS: Main: 332283904 02:18:55 INFO - PROCESS | 2234 | 02:18:56 INFO - PROCESS | 2234 | Cycle 1(1): loaded http://localhost:55810/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55810/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:18:57 INFO - PROCESS | 2234 | RSS: Main: 312078336 02:18:57 INFO - PROCESS | 2234 | 02:18:59 INFO - PROCESS | 2234 | Cycle 1(1): loaded http://localhost:55810/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55810/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:18:59 INFO - PROCESS | 2234 | RSS: Main: 319262720 02:18:59 INFO - PROCESS | 2234 | 02:19:02 INFO - PROCESS | 2234 | Cycle 1(1): loaded http://localhost:55810/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55810/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:19:02 INFO - PROCESS | 2234 | RSS: Main: 316235776 02:19:02 INFO - PROCESS | 2234 | 02:19:05 INFO - PROCESS | 2234 | Cycle 1(1): loaded http://localhost:55810/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55810/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:19:06 INFO - PROCESS | 2234 | RSS: Main: 325427200 02:19:06 INFO - PROCESS | 2234 | 02:19:09 INFO - PROCESS | 2234 | Cycle 1(1): loaded http://localhost:55810/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55810/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:19:09 INFO - PROCESS | 2234 | RSS: Main: 326918144 02:19:09 INFO - PROCESS | 2234 | 02:19:11 INFO - PROCESS | 2234 | Cycle 1(1): loaded http://localhost:55810/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55810/tests/kraken/driver.html?testName=ai-astar) 02:19:12 INFO - PROCESS | 2234 | RSS: Main: 370163712 02:19:12 INFO - PROCESS | 2234 | 02:19:12 INFO - PROCESS | 2234 | __start_tp_report 02:19:12 INFO - PROCESS | 2234 | _x_x_mozilla_page_load 02:19:12 INFO - PROCESS | 2234 | _x_x_mozilla_page_load_details 02:19:12 INFO - PROCESS | 2234 | |i|pagename|runs| 02:19:12 INFO - PROCESS | 2234 | |0;ai-astar;120;114;112;115;113;113;114;114;112;114 02:19:12 INFO - PROCESS | 2234 | |1;audio-beat-detection;147;122;140;184;107;127;127;105;157;106 02:19:12 INFO - PROCESS | 2234 | |2;audio-dft;184;188;185;184;183;183;182;181;185;181 02:19:12 INFO - PROCESS | 2234 | |3;audio-fft;80;76;57;88;59;57;63;64;82;58 02:19:12 INFO - PROCESS | 2234 | |4;audio-oscillator;88;87;95;73;74;68;96;56;69;123 02:19:12 INFO - PROCESS | 2234 | |5;imaging-gaussian-blur;89;86;90;103;135;130;90;86;89;86 02:19:12 INFO - PROCESS | 2234 | |6;imaging-darkroom;94;94;96;94;93;94;102;132;95;93 02:19:12 INFO - PROCESS | 2234 | |7;imaging-desaturate;85;86;83;84;85;85;83;88;85;85 02:19:12 INFO - PROCESS | 2234 | |8;json-parse-financial;55;52;52;53;53;52;52;53;52;52 02:19:12 INFO - PROCESS | 2234 | |9;json-stringify-tinderbox;69;77;77;75;74;71;72;70;72;71 02:19:12 INFO - PROCESS | 2234 | |10;stanford-crypto-aes;85;81;83;84;85;79;93;102;85;82 02:19:12 INFO - PROCESS | 2234 | |11;stanford-crypto-ccm;143;149;137;135;137;134;133;137;201;153 02:19:12 INFO - PROCESS | 2234 | |12;stanford-crypto-pbkdf2;178;176;177;176;178;180;178;177;175;174 02:19:12 INFO - PROCESS | 2234 | |13;stanford-crypto-sha256-iterative;68;70;71;70;69;81;90;91;90;70 02:19:12 INFO - PROCESS | 2234 | __end_tp_report 02:19:12 INFO - PROCESS | 2234 | __start_cc_report 02:19:12 INFO - PROCESS | 2234 | _x_x_mozilla_cycle_collect,2084 02:19:12 INFO - PROCESS | 2234 | __end_cc_report 02:19:12 INFO - PROCESS | 2234 | __startTimestamp1468660752096__endTimestamp 02:19:12 INFO - PROCESS | 2234 | 02:19:12 INFO - PROCESS | 2234 | ------- Summary: start ------- 02:19:12 INFO - PROCESS | 2234 | Number of tests: 14 02:19:12 INFO - PROCESS | 2234 | 02:19:12 INFO - PROCESS | 2234 | [#0] ai-astar Cycles:10 Average:114.10 Median:114.00 stddev:2.28 (2.0%) stddev-sans-first:1.01 02:19:12 INFO - PROCESS | 2234 | Values: 120.0 114.0 112.0 115.0 113.0 113.0 114.0 114.0 112.0 114.0 02:19:12 INFO - PROCESS | 2234 | 02:19:12 INFO - PROCESS | 2234 | [#1] audio-beat-detection Cycles:10 Average:132.20 Median:133.50 stddev:25.38 (19.0%) stddev-sans-first:26.35 02:19:12 INFO - PROCESS | 2234 | Values: 147.0 122.0 140.0 184.0 107.0 127.0 127.0 105.0 157.0 106.0 02:19:12 INFO - PROCESS | 2234 | 02:19:12 INFO - PROCESS | 2234 | [#2] audio-dft Cycles:10 Average:183.60 Median:184.00 stddev:2.12 (1.2%) stddev-sans-first:2.24 02:19:12 INFO - PROCESS | 2234 | Values: 184.0 188.0 185.0 184.0 183.0 183.0 182.0 181.0 185.0 181.0 02:19:12 INFO - PROCESS | 2234 | 02:19:12 INFO - PROCESS | 2234 | [#3] audio-fft Cycles:10 Average:68.40 Median:70.00 stddev:11.86 (16.9%) stddev-sans-first:11.82 02:19:12 INFO - PROCESS | 2234 | Values: 80.0 76.0 57.0 88.0 59.0 57.0 63.0 64.0 82.0 58.0 02:19:12 INFO - PROCESS | 2234 | 02:19:12 INFO - PROCESS | 2234 | [#4] audio-oscillator Cycles:10 Average:82.90 Median:80.50 stddev:19.10 (23.7%) stddev-sans-first:20.17 02:19:12 INFO - PROCESS | 2234 | Values: 88.0 87.0 95.0 73.0 74.0 68.0 96.0 56.0 69.0 123.0 02:19:12 INFO - PROCESS | 2234 | 02:19:12 INFO - PROCESS | 2234 | [#5] imaging-gaussian-blur Cycles:10 Average:98.40 Median:87.50 stddev:18.67 (21.3%) stddev-sans-first:19.49 02:19:12 INFO - PROCESS | 2234 | Values: 89.0 86.0 90.0 103.0 135.0 130.0 90.0 86.0 89.0 86.0 02:19:12 INFO - PROCESS | 2234 | 02:19:12 INFO - PROCESS | 2234 | [#6] imaging-darkroom Cycles:10 Average:98.70 Median:94.00 stddev:11.99 (12.8%) stddev-sans-first:12.60 02:19:12 INFO - PROCESS | 2234 | Values: 94.0 94.0 96.0 94.0 93.0 94.0 102.0 132.0 95.0 93.0 02:19:12 INFO - PROCESS | 2234 | 02:19:12 INFO - PROCESS | 2234 | [#7] imaging-desaturate Cycles:10 Average:84.90 Median:85.00 stddev:1.45 (1.7%) stddev-sans-first:1.54 02:19:12 INFO - PROCESS | 2234 | Values: 85.0 86.0 83.0 84.0 85.0 85.0 83.0 88.0 85.0 85.0 02:19:12 INFO - PROCESS | 2234 | 02:19:12 INFO - PROCESS | 2234 | [#8] json-parse-financial Cycles:10 Average:52.60 Median:52.50 stddev:0.97 (1.8%) stddev-sans-first:0.50 02:19:12 INFO - PROCESS | 2234 | Values: 55.0 52.0 52.0 53.0 53.0 52.0 52.0 53.0 52.0 52.0 02:19:12 INFO - PROCESS | 2234 | 02:19:12 INFO - PROCESS | 2234 | [#9] json-stringify-tinderbox Cycles:10 Average:72.80 Median:73.00 stddev:2.82 (3.9%) stddev-sans-first:2.64 02:19:12 INFO - PROCESS | 2234 | Values: 69.0 77.0 77.0 75.0 74.0 71.0 72.0 70.0 72.0 71.0 02:19:12 INFO - PROCESS | 2234 | 02:19:12 INFO - PROCESS | 2234 | [#10] stanford-crypto-aes Cycles:10 Average:85.90 Median:84.50 stddev:6.76 (8.0%) stddev-sans-first:7.16 02:19:12 INFO - PROCESS | 2234 | Values: 85.0 81.0 83.0 84.0 85.0 79.0 93.0 102.0 85.0 82.0 02:19:12 INFO - PROCESS | 2234 | 02:19:12 INFO - PROCESS | 2234 | [#11] stanford-crypto-ccm Cycles:10 Average:145.90 Median:140.00 stddev:20.46 (14.6%) stddev-sans-first:21.68 02:19:12 INFO - PROCESS | 2234 | Values: 143.0 149.0 137.0 135.0 137.0 134.0 133.0 137.0 201.0 153.0 02:19:12 INFO - PROCESS | 2234 | 02:19:12 INFO - PROCESS | 2234 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.90 Median:177.50 stddev:1.73 (1.0%) stddev-sans-first:1.79 02:19:12 INFO - PROCESS | 2234 | Values: 178.0 176.0 177.0 176.0 178.0 180.0 178.0 177.0 175.0 174.0 02:19:12 INFO - PROCESS | 2234 | 02:19:12 INFO - PROCESS | 2234 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.00 Median:76.00 stddev:9.88 (13.0%) stddev-sans-first:9.92 02:19:12 INFO - PROCESS | 2234 | Values: 68.0 70.0 71.0 70.0 69.0 81.0 90.0 91.0 90.0 70.0 02:19:12 INFO - PROCESS | 2234 | -------- Summary: end -------- 02:19:12 INFO - PROCESS | 2234 | 02:19:12 INFO - TEST-INFO | 2234: exit 0 02:19:12 INFO - TEST-OK | kraken | took 60003ms 02:19:12 INFO - SUITE-END | took 1176s 02:19:13 INFO - Completed test suite (00:19:36) 02:19:13 INFO - javascript benchmark 02:19:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3157.6368717597475, 3313.933721325572, 3322.9003129906105, 3370.5955285365744, 3376.9831150844243, 5506.201600767887, 5577.358603760569, 5577.8047768328115, 5662.801801936933, 5706.65760054397, 5120.359955005624, 5573.829495805886, 5628.915566266509, 5657.3494048184475, 5779.95960727069, 5079.720615366154, 5080.339555857733, 5167.431582525919, 5168.508991645787, 5271.488129415587, 5237.6071794615555, 5264.447233040533, 5277.076511610472, 5277.076511610472, 5285.920711189336, 5098.362704661918, 5132.538071573576, 5134.64057515974, 5189.506996835295, 5219.739013049332, 5137.845864624067, 5166.948330516684, 5174.939137476808, 5254.343207099113, 5284.445133260991, 4214.325707886724, 5142.408623008365, 5171.974140129313, 5211.2443695663915, 5227.8170264040955, 5517.641353312062, 5616.775328986836, 5704.1443783432405, 5716.685582292972, 5784.479396854304, 6170.784022559234, 6187.597806142587, 6257.624542527463, 6268.310485846553, 6271.6864156792435, 6250.531210159256, 6299.811005669837, 6316.968415157895, 6329.68351582419, 6335.968320158416, 5617.21359009739, 5666.46168613981, 5680.318361796611, 5823.272090988627, 5839.795607153729, 6271.498280137578, 6279.968600157062, 6302.936970630235, 6331.525135614847, 6353.809385718436, 6120.449159575571, 6120.510359171256, 6136.6624835633575, 6138.478229350466, 6139.4781443577795, 6206.596571222946, 6214.8135555933395, 6244.406781355764, 6262.499000079983, 6286.057091436322, 5954.285485741739, 6006.069059295816, 6101.03536932808, 6184.134221209034, 6186.659733714599, 6262.843428914222, 6264.467520260829, 6273.0904018917, 6274.435300822948, 6286.4342209201395, 4957.851264462072, 5001.44984051754, 5005.449400565934, 5007.399112106497, 5046.772895219724, 4393.0774537347515, 4422.071365013383, 4474.284114541722, 4489.259272220047, 4510.120526497367, 5580.190872323572, 5614.550835933115, 5619.438056194348, 5642.097264437671, 5643.661380317194, 5500.5973476763165, 5969.701514924236, 6117.736059405574, 6190.50475961922, 6194.473469755031, 5620.100783874561, 5634.746436410372, 5653.208550802891, 5657.915131273035, 5691.146328050825, 5297.099493086184, 5384.6499977501335, 5404.594655400782, 5412.539934105566, 5417.756200970887, 5276.498732620395, 6231.594946328474, 6271.749130034747, 6311.747530098744, 6357.078223657636, 6296.716647750954, 6305.621662700252, 6305.653189074645, 6333.746650134128, 6336.683165841598, 6349.174607301022, 6357.523185761179, 6358.491320694426, 6358.904616430665, 6359.49124070064, 5915.91126133117, 6105.572609917263, 6113.385090542009, 6118.694065296629, 6174.8147555573405, 6246.281677606951, 6250.593711408744, 6262.53031022666, 6274.498040156868, 6278.748850045947, 6124.295705993868, 6125.0, 6128.540359472968, 6135.6932153393045, 6136.23297087864, 4929.383827021622, 5788.957987562279, 5834.03738383162, 5887.234659494238, 5932.851678708067, 5769.451902069297, 5770.278715160605, 5776.277965254343, 5786.971065144816, 5793.681347525927, 5875.3243377012195, 5888.735006924613, 5896.55775816824, 5898.233229680114, 5965.105234214902, 5714.342850572238, 5879.676617786063, 5917.792877249276, 5934.910976335272, 5964.075568286922], "value": 5649.419282846593, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [469.3148003914285, 515.6493584362634, 518.6499113098658, 521.8512723873697, 539.6788910598195, 32170.356592868127, 41094.38358424626, 42448.36327455083, 42555.936101597435, 42589.0, 43764.562354376445, 44040.77979610106, 44054.89862753433, 44135.33796993048, 44331.89170270745, 7212.747553835617, 9634.518274086302, 9818.165455936254, 9820.0, 9826.115649591535, 8820.117988201193, 11866.7626647467, 11975.580854670088, 11993.760124797498, 12378.319192444413, 12039.63881083569, 12049.337286449241, 12120.212186207913, 12127.818082728721, 12153.453094610764, 8767.824643507094, 9829.655962041294, 11198.880111988736, 12015.519379224865, 12124.757504849898, 11873.821892671616, 11949.64151075469, 11954.0, 11966.82049769254, 11978.0, 40277.597224027675, 40489.19021619551, 40548.79725601353, 40582.18835623271, 40607.18785624271, 3711.870084547028, 3805.8667946621736, 3833.5016447861876, 3915.549711783153, 4335.5014173370155, 31030.689693103006, 31366.52950205749, 32279.193020174447, 32788.67211327904, 32866.50700239498, 3301.570795796555, 3307.520409540627, 3348.430766769655, 3386.0857568456627, 3433.1073920780777, 489128.0, 490131.0986890121, 490473.0, 495919.0, 497258.0, 57667.711661441426, 58628.534286643175, 58730.23809285728, 58748.0, 58857.0, 56044.0, 56070.15894761582, 56103.0, 56144.43855561474, 56225.0, 16346.509604711877, 16608.418705345495, 16611.83388166127, 16616.750748738777, 16649.91675041617, 11750.882491174978, 11968.401579920968, 12808.231506109663, 12930.482780688855, 13022.95816334691, 15286.92991490585, 15293.694126117416, 15295.235238238043, 15324.080555166725, 15479.303431345617, 5336.866578335573, 5373.205932914862, 6407.743690252338, 6469.708863101172, 6606.8017959461295, 916.6333466613409, 948.127722495306, 989.8020395920845, 995.865558149655, 1096.7970925378831, 26899.0, 31627.36745265082, 31721.61464304609, 32017.19957001047, 32133.463441696633, 1084.0839490630406, 1118.8657361116718, 1127.8590176227972, 1148.9827652585218, 1227.049036996335, 15579.84558717249, 16390.180490975403, 16472.25874835635, 16560.50318490423, 16880.746788797933, 647245.0, 648963.0, 652665.0, 652977.0, 654542.5455854498, 58556.70721646365, 58575.0, 58618.0, 58719.706401467716, 58993.0, 1269.4097244781221, 1384.0795870746042, 1408.0917808013774, 1424.3376829774204, 1434.168182454174, 31416.057518274487, 31857.522137168424, 31877.20306992344, 32613.021609351756, 32710.0, 51683.224751628004, 52247.0, 52370.476295238084, 52386.21420678769, 52462.47537524576, 526970.0, 528814.0, 530123.0, 533259.0, 533825.0, 40694.0, 41204.79397602993, 42046.78976605098, 42184.789076054425, 42273.0, 48286.5171348282, 48567.27149092837, 49232.261516078004, 49434.752826235635, 49931.0, 39962.400563992145, 40030.0, 40483.79758101191, 41902.37146442745, 42795.14409711851, 39414.40878386769, 40807.34894552907, 41150.0, 41150.58849411467, 41270.9682257934, 39869.60130398775, 40185.79442616726, 40433.0, 41143.0, 41315.7934210327, 34396.82801585976, 34968.9509314721, 35559.93320200398, 35589.644103559665, 35836.28327433489, 25787.484250314512, 25859.224223273333, 26403.471930560896, 26410.86794566015, 26434.339141521617], "value": 20784.30661342635, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.92981368633093, 372.36140019865934, 387.3763241181697, 391.4148348219412, 409.9852864161201, 16095.839041609595, 16102.758458623115, 21466.677999830015, 21716.80557569333, 21872.1251149954, 20553.691694624555, 20938.0, 21271.596544552875, 21764.911754412296, 21827.890860545715, 5124.718140502271, 5727.856803579913, 5964.0, 5988.401159883999, 5988.700564971756, 5821.243238379005, 5952.19645347879, 5964.314103878044, 5999.250093738283, 6004.0693692477735, 5606.915896261559, 5747.936631723123, 5756.395578464264, 5772.480476757091, 5845.357010728838, 5101.464346243628, 5843.620164689303, 5877.413098463412, 5889.381614930414, 5897.7346019429015, 5091.694498330112, 5123.0, 5123.410807757081, 5125.103106956305, 5127.743612819381, 26543.20370388872, 26600.467990640078, 26618.669066546594, 26942.46115077707, 27025.72974270252, 2437.4759426723163, 2939.235798692334, 2972.390659914712, 3139.136737397234, 3177.8887738929257, 15757.527274181793, 15801.288941997638, 15940.442084526985, 16131.032138071754, 16336.591585210346, 1721.5523963769385, 1766.5053784940237, 1809.8918926964345, 1829.5461299205563, 1952.453313072342, 120085.0, 124236.0, 124770.75229247683, 124842.37578812138, 125858.11212831814, 10778.568857245778, 11161.818881618861, 11174.553017879356, 11220.0, 11347.886521134684, 13900.582982510541, 14054.718905622034, 14084.295785210903, 14089.43642254319, 14104.08323458972, 8790.164934331227, 8829.293656507463, 8871.556422178994, 8888.0, 8995.505247211335, 8855.557222138867, 8857.557122143868, 8877.778055548662, 8994.640214391482, 9093.818123637622, 6651.235107962646, 6790.185177778698, 6791.796246112624, 6857.451403887677, 6929.688164032631, 1398.5245016694373, 1676.9100084944882, 1834.8807327523668, 1873.381784011273, 1875.9531011724816, 348.4546684438845, 383.86372837642796, 880.8942926848691, 911.0616065452595, 964.8215080210043, 11602.303861768321, 12029.639110826689, 12145.210561313486, 12216.389180540937, 12217.0, 424.9745015299092, 444.822071171534, 447.16157205240177, 450.1671906972141, 450.54044874228106, 6064.818055458343, 6124.418180272867, 6156.938430615726, 6240.375962403814, 6264.342244064308, 601589.0, 603140.8743650204, 612846.0, 616540.9172954106, 616888.0, 94264.58603120959, 127398.08902866465, 127662.0, 150445.0, 152793.0, 435.4513313225359, 443.4833419066816, 446.9039156581351, 449.57964303376446, 452.0100978856294, 11132.666020019413, 11275.379854107774, 11353.886461135284, 11452.08383329315, 11467.025302849352, 56639.71680141573, 57245.42754572401, 59122.704386477795, 59217.70391148017, 59404.70297648657, 86918.130818691, 87543.1245687535, 87732.56133719545, 87759.97920166244, 89579.0, 7787.221277872394, 7916.802079948047, 7921.603919804102, 7960.164182760958, 8205.712800052208, 9015.504147271962, 12066.0, 12267.57063502773, 12347.62957111288, 12579.245245285312, 10574.524146413276, 10844.891551084389, 10965.232433729563, 11023.393713345842, 11097.944510277397, 10355.17158627323, 10507.94746026265, 10675.199360047873, 10838.645169353831, 10847.891521084688, 11087.889994787594, 11180.0, 11240.381779001906, 11303.943480282545, 11329.376884271443, 6875.48433867472, 6876.17485901695, 7319.268073192638, 7377.594232317274, 8224.712135075244, 9304.413760076828, 11086.501107450023, 11121.388323642277, 11218.887811122111, 11272.154588405738], "value": 8934.618690766027, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1544.2741911301687, 1583.4220509514028, 1780.8842425242358, 1901.9904900475512, 1911.6176764647066, 8580.313574914007, 8851.159139881702, 9027.954860225705, 9054.275657947364, 9059.682911098113, 7844.372450203984, 7994.4803587766755, 8004.719834805783, 8157.102718700939, 8798.648054077845, 2132.8720276783365, 2160.470684682255, 2284.3489605462446, 2356.8703721295324, 2359.044586942291, 2361.2680069178523, 2383.797377222938, 2384.7376788553333, 2385.33210701004, 2385.5825230584664, 2177.7151480626426, 2181.4982554012586, 2367.396313939943, 2368.8933997970134, 2370.4547953970596, 2086.488810241485, 2156.892155392224, 2160.384290477214, 2160.967585486219, 2293.839431239814, 2354.8233882458885, 2389.7371289158177, 2390.6294524348755, 2391.158807718065, 2392.940176495584, 9029.322800789967, 9084.364094513387, 9090.363674542785, 9092.408993415407, 9095.226905713022, 5246.921296180561, 5414.8917021659345, 5418.268533747933, 5423.5932305076885, 5440.972795136038, 3493.7030352420074, 3512.5784905811465, 3570.3394871948512, 3614.078410005458, 3615.7107431405425, 1072.6460268111505, 1078.946052697362, 1082.9729256768564, 1090.596479302663, 1103.7957977774058, 11983.760324793542, 12021.699457513545, 12038.157328986887, 12044.879551204463, 12066.09504287182, 4592.954070459319, 4630.86107416778, 4725.787339569728, 4794.7842347094465, 4795.808167673254, 10092.848607270897, 10093.899061009295, 10096.646617368357, 10113.494325283706, 10140.13808826258, 5394.946050539523, 5396.811111611153, 5399.217113518518, 5400.567954563626, 5410.296661433988, 4767.189577771718, 4929.0, 4930.260460930817, 4951.702897826142, 4988.575971042502, 3442.896713098611, 3538.5930617978747, 3834.386498160281, 4011.7592944422845, 4020.6582440492884, 1126.341090462089, 1286.1061562214359, 1289.3231053696697, 1311.6786387334973, 1321.7951217561101, 839.7816567692322, 950.5627411390838, 960.2270172511196, 973.7368139806362, 1001.5342865567546, 5910.468057874726, 5926.318473375617, 5965.701714914237, 6136.570440069152, 6151.415615516519, 948.9051094890456, 952.2334520710895, 965.9082387173207, 977.4623956823718, 1003.2124782046059, 4012.137390461065, 4064.1262128642484, 4067.3695577185586, 4141.275276826604, 4141.64795992338, 8792.8681069784, 10492.0, 10905.236633435583, 10964.671059868217, 11008.724781880357, 10930.56277748897, 10949.124070074375, 10974.122070234362, 10977.451127443595, 10978.890211097787, 7832.0, 8229.05365882919, 8392.034915984617, 8415.074341822394, 8431.451955622864, 6077.4834139098675, 6079.0, 6195.194624698941, 6196.256449226122, 6235.812925612239, 10213.795724085625, 10257.948710256402, 10350.637727679496, 10365.170786337225, 10366.792664146524, 9916.702498925044, 10095.545700443645, 10528.263021588415, 10537.57849686004, 10616.0, 6959.930400695928, 7626.923730762622, 7807.180246074307, 7812.492188007761, 7825.256600622932, 7517.736879209201, 7841.647125879235, 7946.92053079485, 8035.035795704319, 8037.799055023671, 7670.0, 7673.731419400294, 7718.189590092959, 7800.102988156208, 7872.448928574945, 7206.4595155364605, 7505.587192704454, 7525.36034437079, 7603.0, 7606.695732170651, 6450.870982580416, 6991.2309645937885, 7033.472489563405, 7081.716731330689, 7099.893501597377, 4199.223143718422, 5071.898562028665, 5119.104156772475, 5167.379914410295, 5182.6113041521285, 5144.331236939323, 5220.947790522198, 5259.89480210386, 5301.840944771663, 5387.434319396563], "value": 4638.5763491977805, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.74954787526354, 305.42427524117033, 308.91350421881873, 314.4716875648909, 317.1231544778686, 4335.393044973703, 5322.228276899852, 5351.839444816662, 5387.973060134699, 5391.757370918308, 5187.6628019178725, 5206.661566998152, 5263.105272103742, 5281.419043905177, 5312.893742125155, 3295.0, 3617.503922293609, 3682.1773222254888, 3830.8467661293516, 3961.7028722845757, 3530.523379343794, 3797.2215695782297, 3930.2139572085553, 4044.1507283470505, 4106.618161466357, 3453.513470301379, 3623.804144632278, 3957.9010524736823, 3970.702197335197, 3995.5205375354853, 3108.518179682153, 3578.6600272974156, 3842.269968705937, 3932.6460618544183, 4012.799360032016, 2897.9710202897913, 3268.1176082457605, 3482.703970162539, 3526.2594855080206, 3552.8401221944814, 3867.5552311484257, 4680.7191568506, 4757.976210118962, 5142.0, 5149.356330458692, 4215.346621273708, 4578.748168850712, 4595.149897269016, 4621.8739379884955, 4691.577758001797, 5078.7968481260705, 5556.527695145917, 5606.411326810708, 5630.5495560355075, 5639.548836093103, 3843.8078096095364, 3854.383298672199, 3863.3818589025623, 3885.572587015426, 3911.6283953024704, 5431.158170483542, 5818.76724930998, 5963.52291816658, 5975.880482390371, 5977.850553736148, 3440.552728145375, 3852.653261206505, 3861.942070868939, 3865.729398942103, 3869.9613003870163, 3946.7039972002217, 3960.7821569813937, 3961.346377847623, 3964.4647972523917, 3968.543617483969, 3680.392735198716, 4116.958830411718, 4598.5970381917805, 4625.583697467244, 4632.2125238709505, 4954.281629163823, 5301.628885978022, 5553.583481238923, 5590.888182236333, 5600.383957764642, 1174.8369114576678, 1249.775040492702, 1335.8998075144402, 1339.638297659646, 1341.6914109754616, 737.4174402222292, 744.9515781474187, 775.337086790797, 780.4614815777096, 817.8509194581623, 3742.4760533525327, 4198.6011328923705, 4613.723176609415, 5073.898522029539, 5147.6911385317, 5133.974330128325, 5344.35867695879, 5470.64440811346, 5485.9725701371235, 5643.351014633287, 4273.764942928106, 5157.742112894341, 5170.844874653766, 5183.444966510107, 5242.13504771716, 2350.8354415190774, 2506.4611108611734, 2804.551271796503, 2951.188423183599, 3014.668386477486, 4557.897891897319, 5324.92012619811, 5657.745401456944, 5888.114823736339, 5914.8817023659285, 4599.0342028174455, 5143.717095559706, 5408.486193811582, 5600.515863296229, 5746.0, 5122.974385128051, 5895.263092113486, 5898.522620499486, 5913.556483263773, 6001.1898393717165, 4767.736549814233, 5728.1694154348215, 5735.7705691773535, 5736.110902810071, 5832.591718579659, 5527.39198688152, 5538.196961440488, 5719.8856022880145, 5749.511291540266, 5757.510611598061, 5099.87250318745, 5513.558915286848, 5615.943840561542, 5875.294964604275, 6005.849853753691, 3802.94295585572, 3869.458275841328, 3886.9222615548097, 3893.552241492265, 3915.667168290727, 4821.661494175392, 5026.623003274696, 5446.754896029609, 5526.292116862789, 5779.422057794187, 4800.4479484859685, 5524.486358706859, 5532.529734972572, 5532.612717109763, 5548.361938377138, 5048.873778155428, 5501.862453438696, 5534.363548192009, 5600.607957443103, 5639.774409023593, 5156.56169225605, 5157.742112894415, 5212.611802328517, 5529.253550770755, 5551.916721249104, 4689.577937985598, 5023.397192336943, 5038.924416133834, 5401.432849550898, 5590.301212348457, 4911.189653707098, 5304.939012197653, 5326.893462130658, 5382.138857782736, 5420.9186862197885], "value": 3995.9284955806534, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [734.9669264883078, 740.8555331710319, 775.0621747685301, 809.279741030483, 831.2601784411871, 15143.394264229431, 16478.7528187077, 16503.75244371334, 16510.174491275448, 16529.0, 15067.246637668128, 15243.771343429857, 15262.542123736277, 15270.0, 15335.233238338094, 721.9855602887939, 741.2550386861197, 882.4793371910572, 890.4924193209873, 903.5346796399859, 895.9148880856341, 904.7873749668855, 909.7498187998287, 909.895362033368, 910.1171863292595, 944.4805357053627, 946.5693109635099, 946.7065209784988, 947.5735918836517, 947.7535840681403, 747.5141158247128, 920.5029284186531, 941.9058094190594, 955.1165172215673, 958.9472579008152, 706.3889735378912, 706.6502081469705, 706.780897921646, 707.7876637008928, 709.5919846088468, 15269.0, 15298.311575979107, 15332.693346133015, 15333.463328783551, 15335.616609584738, 14259.78610320846, 14405.279736013263, 14424.855751442456, 14436.566902992927, 14442.566722998326, 13103.344832758325, 13138.540151094665, 13238.933805331008, 13343.332833358294, 13468.326583670876, 4621.884452888671, 4670.883227919295, 4696.342512048316, 4723.4568024677255, 4835.516448355172, 102891.0, 113505.43247283793, 115256.42371788171, 116200.0, 117180.24229636477, 11493.080553555696, 11565.479553420117, 11575.016123629417, 11777.116716246315, 12100.757984840255, 18346.541336466697, 22731.431714206945, 22764.658530122062, 22862.08551657915, 22932.0, 7958.840823183504, 8024.117347091816, 8111.148329425326, 8133.4306598538715, 8182.795430114175, 4370.694051416371, 4378.890527736832, 4418.955810441855, 4430.5126436092005, 4616.746078965689, 3576.624454432299, 3669.908252293714, 3701.6668499835146, 3703.2593481303306, 3708.8887333380044, 687.2371667449128, 687.8796210663214, 694.5797792335666, 694.7776711452387, 696.7352406085756, 552.5386302437429, 591.6982339007051, 601.2484394506866, 601.6841158391792, 601.7653115285042, 9199.494027828534, 9200.723978280663, 9243.445393276425, 9311.48786816718, 9324.953375233215, 608.9177960975212, 636.2651137935692, 636.6375955455329, 640.3564417760122, 640.7148818775694, 3909.5730058529, 4341.761203133858, 4385.210662080793, 4423.203823311835, 4496.797644106023, 117331.0, 129054.35472822764, 141908.2904585491, 142357.0, 145853.27073364778, 112274.0, 117145.0, 117483.0, 119813.0, 122115.0, 77187.0, 78856.21143788603, 79982.6000869992, 80145.5956321323, 115546.0, 7862.33170180541, 9778.462184579916, 9825.0, 9836.409815411098, 9930.900690992998, 54708.72645636746, 60057.0, 65656.67171664111, 65668.34331656623, 66553.33616659623, 94099.0, 101353.49323253632, 102780.0, 104230.0, 104264.0, 13691.178529288274, 13742.0, 13828.516001939885, 13888.86111138916, 14252.501162459264, 13412.396442160321, 15431.537053888402, 15536.61158471002, 15751.44869929547, 16473.176341182654, 12838.16551924122, 14395.0, 14870.628234294229, 14886.0, 14891.032082914577, 14031.438742450187, 14132.0, 14408.783868241775, 15033.699326013637, 15051.476726159734, 13462.0, 13616.251106188858, 13953.302334883418, 14204.0, 14349.660066498107, 9711.36876103051, 11431.028362589273, 11490.255526161862, 11585.246958947642, 12154.817677735018, 11634.709132271422, 11738.712871780006, 11752.294862308288, 11854.348010859485, 11889.405529723306], "value": 7999.603422218945, "unit": "score"}], "name": "dromaeo_css", "value": 7333.6860819876065, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 114.0, 112.0, 115.0, 113.0, 113.0, 114.0, 114.0, 112.0, 114.0], "value": 114.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 122.0, 140.0, 184.0, 107.0, 127.0, 127.0, 105.0, 157.0, 106.0], "value": 132.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 188.0, 185.0, 184.0, 183.0, 183.0, 182.0, 181.0, 185.0, 181.0], "value": 183.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 76.0, 57.0, 88.0, 59.0, 57.0, 63.0, 64.0, 82.0, 58.0], "value": 68.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 95.0, 73.0, 74.0, 68.0, 96.0, 56.0, 69.0, 123.0], "value": 82.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 90.0, 103.0, 135.0, 130.0, 90.0, 86.0, 89.0, 86.0], "value": 98.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 96.0, 94.0, 93.0, 94.0, 102.0, 132.0, 95.0, 93.0], "value": 98.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 83.0, 84.0, 85.0, 85.0, 83.0, 88.0, 85.0, 85.0], "value": 84.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 52.0, 52.0, 53.0, 53.0, 52.0, 52.0, 53.0, 52.0, 52.0], "value": 52.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 77.0, 77.0, 75.0, 74.0, 71.0, 72.0, 70.0, 72.0, 71.0], "value": 72.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 81.0, 83.0, 84.0, 85.0, 79.0, 93.0, 102.0, 85.0, 82.0], "value": 85.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 149.0, 137.0, 135.0, 137.0, 134.0, 133.0, 137.0, 201.0, 153.0], "value": 145.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 176.0, 177.0, 176.0, 178.0, 180.0, 178.0, 177.0, 175.0, 174.0], "value": 176.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 71.0, 70.0, 69.0, 81.0, 90.0, 91.0, 90.0, 70.0], "value": 77.0, "unit": "score"}], "name": "kraken", "value": 1474.3000000000002, "alertThreshold": 2.0}]} 02:19:13 INFO - Return code: 0 02:19:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:19:13 INFO - # TBPL SUCCESS # 02:19:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:19:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:19:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:19:13 INFO - 2016-07-16 02:19:13.405 screenresolution[2238:26333] starting screenresolution argv=screenresolution get 02:19:13 INFO - 2016-07-16 02:19:13.407 screenresolution[2238:26333] Display 0: 1600x1200x32@60 02:19:13 INFO - 2016-07-16 02:19:13.413 screenresolution[2239:26336] starting screenresolution argv=screenresolution list 02:19:13 INFO - Available Modes on Display 0 02:19:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:19:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:19:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:19:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:19:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:19:13 INFO - Intel Iris: 02:19:13 INFO - Chipset Model: Intel Iris 02:19:13 INFO - Type: GPU 02:19:13 INFO - Bus: Built-In 02:19:13 INFO - VRAM (Dynamic, Max): 1536 MB 02:19:13 INFO - Vendor: Intel (0x8086) 02:19:13 INFO - Device ID: 0x0a2e 02:19:13 INFO - Revision ID: 0x0009 02:19:13 INFO - Displays: 02:19:13 INFO - Display: 02:19:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:19:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:19:13 INFO - Display Serial Number: 1600x1200 60 02:19:13 INFO - Main Display: Yes 02:19:13 INFO - Mirror: Off 02:19:13 INFO - Online: Yes 02:19:13 INFO - Rotation: Supported 02:19:13 INFO - Return code: 0 02:19:13 INFO - Running post-action listener: _resource_record_post_action 02:19:13 INFO - ##### 02:19:13 INFO - ##### Finished run-tests step (success) 02:19:13 INFO - ##### 02:19:13 INFO - Running post-run listener: _resource_record_post_run 02:19:13 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 528384; Write bytes: 703626752; Read time: 6; Write time: 2115 02:19:13 INFO - TinderboxPrint: CPU usage
53.3% 02:19:13 INFO - TinderboxPrint: I/O read bytes / time
528,384 / 6 02:19:13 INFO - TinderboxPrint: I/O write bytes / time
703,626,752 / 2,115 02:19:13 INFO - TinderboxPrint: CPU idle
2,263.9 (46.7%) 02:19:13 INFO - TinderboxPrint: CPU system
967.7 (20.0%) 02:19:13 INFO - TinderboxPrint: CPU user
1,618.1 (33.4%) 02:19:13 INFO - TinderboxPrint: Swap in / out
407,306,240 / 0 02:19:13 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 390416896; Write bytes: 384820736; Read time: 29154; Write time: 762 02:19:13 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 528384; Write bytes: 293202432; Read time: 6; Write time: 1301 02:19:14 INFO - Running post-run listener: _upload_blobber_files 02:19:14 INFO - Blob upload gear active. 02:19:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:19:14 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 02:19:14 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:19:14 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:19:14 INFO - (blobuploader) - INFO - Open directory for files ... 02:19:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:19:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:19:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:19:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:19:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:19:15 INFO - (blobuploader) - INFO - Done attempting. 02:19:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:19:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:19:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:19:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:19:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:19:16 INFO - (blobuploader) - INFO - Done attempting. 02:19:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:19:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:19:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:19:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:19:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:19:19 INFO - (blobuploader) - INFO - Done attempting. 02:19:19 INFO - (blobuploader) - INFO - Iteration through files over. 02:19:19 INFO - Return code: 0 02:19:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:19:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:19:19 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0c6fb62519248980870af218f08c82c22ed6ff3b9b3e8e4c61a107123318b56bc51e143bb584c983ecacc2e9ab581f0515db2a44d6e4d8885c20a6048f25ac93", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d3555fb6cbe81f77b4b8c92481fa91282e9803081007cdf92c97b948267853c459f5c2d88a37ec42ea20b92e25c3dbc71f660e1c3dfef4b5b7844d49f36ae940", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a3ac9376359737c26892e43e8e3cc81c6459b944f8444d811ea899fcf1d3a2680a9f8cd86ac665ac0a4b1f9c9fab9ad52f107d9458ab27efe3afe5a798071738"} 02:19:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:19:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:19:19 INFO - Contents: 02:19:19 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0c6fb62519248980870af218f08c82c22ed6ff3b9b3e8e4c61a107123318b56bc51e143bb584c983ecacc2e9ab581f0515db2a44d6e4d8885c20a6048f25ac93", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d3555fb6cbe81f77b4b8c92481fa91282e9803081007cdf92c97b948267853c459f5c2d88a37ec42ea20b92e25c3dbc71f660e1c3dfef4b5b7844d49f36ae940", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a3ac9376359737c26892e43e8e3cc81c6459b944f8444d811ea899fcf1d3a2680a9f8cd86ac665ac0a4b1f9c9fab9ad52f107d9458ab27efe3afe5a798071738"} 02:19:19 INFO - Running post-run listener: copy_logs_to_upload_dir 02:19:19 INFO - Copying logs to upload dir... 02:19:19 INFO - mkdir: /builds/slave/test/build/upload/logs 02:19:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.224176 ========= master_lag: 1.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-07-16 02:19:20.468918) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-07-16 02:19:20.473214) ========= 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.V0fwv76H58/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.IE2FZyiDpT/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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/Fx-Team/sha512/0c6fb62519248980870af218f08c82c22ed6ff3b9b3e8e4c61a107123318b56bc51e143bb584c983ecacc2e9ab581f0515db2a44d6e4d8885c20a6048f25ac93", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d3555fb6cbe81f77b4b8c92481fa91282e9803081007cdf92c97b948267853c459f5c2d88a37ec42ea20b92e25c3dbc71f660e1c3dfef4b5b7844d49f36ae940", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a3ac9376359737c26892e43e8e3cc81c6459b944f8444d811ea899fcf1d3a2680a9f8cd86ac665ac0a4b1f9c9fab9ad52f107d9458ab27efe3afe5a798071738"} build_url:https://queue.taskcluster.net/v1/task/Yjn1i2tSRw6h0GFygfc2gQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011285 build_url: 'https://queue.taskcluster.net/v1/task/Yjn1i2tSRw6h0GFygfc2gQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0c6fb62519248980870af218f08c82c22ed6ff3b9b3e8e4c61a107123318b56bc51e143bb584c983ecacc2e9ab581f0515db2a44d6e4d8885c20a6048f25ac93", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d3555fb6cbe81f77b4b8c92481fa91282e9803081007cdf92c97b948267853c459f5c2d88a37ec42ea20b92e25c3dbc71f660e1c3dfef4b5b7844d49f36ae940", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a3ac9376359737c26892e43e8e3cc81c6459b944f8444d811ea899fcf1d3a2680a9f8cd86ac665ac0a4b1f9c9fab9ad52f107d9458ab27efe3afe5a798071738"}' ========= master_lag: 1.36 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-07-16 02:19:21.847389) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-16 02:19:21.848143) ========= 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.V0fwv76H58/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.IE2FZyiDpT/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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.005351 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-16 02:19:21.875405) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-16 02:19:21.875699) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-16 02:19:21.876025) ========= ========= Total master_lag: 2.64 =========