builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0166 starttime: 1467935283.72 results: success (0) buildid: 20160707152724 builduid: ffb37809d7b74efc9bc7ac8f068d5ed5 revision: 9a123c6c6dbf1715bc50db5103ded53f7865b3a0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-07 16:48:03.724460) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-07 16:48:03.724950) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-07 16:48:03.725297) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-07 16:48:03.914159) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 16:48:03.914594) ========= 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.lr5pYTCItg/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.FC62Ja0KYP/Listeners TMPDIR=/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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-07 16:48:03-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.42M=0.001s 2016-07-07 16:48:04 (9.42 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.445828 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 16:48:04.388187) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 16:48:04.388535) ========= 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.lr5pYTCItg/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.FC62Ja0KYP/Listeners TMPDIR=/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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.034028 ========= master_lag: 0.10 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 16:48:04.522894) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-07 16:48:04.523322) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 9a123c6c6dbf1715bc50db5103ded53f7865b3a0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 9a123c6c6dbf1715bc50db5103ded53f7865b3a0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lr5pYTCItg/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.FC62Ja0KYP/Listeners TMPDIR=/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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-07 16:48:04,598 truncating revision to first 12 chars 2016-07-07 16:48:04,598 Setting DEBUG logging. 2016-07-07 16:48:04,598 attempt 1/10 2016-07-07 16:48:04,598 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/9a123c6c6dbf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-07 16:48:05,810 unpacking tar archive at: autoland-9a123c6c6dbf/testing/mozharness/ program finished with exit code 0 elapsedTime=1.494259 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-07 16:48:06.040222) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-07 16:48:06.040574) ========= script_repo_revision: 9a123c6c6dbf1715bc50db5103ded53f7865b3a0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-07 16:48:06.040951) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-07 16:48:06.041264) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-07 16:48:06.064121) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 12 secs) (at 2016-07-07 16:48:06.064442) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lr5pYTCItg/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.FC62Ja0KYP/Listeners TMPDIR=/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 16:48:06 INFO - MultiFileLogger online at 20160707 16:48:06 in /builds/slave/test 16:48:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 16:48:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:48:06 INFO - {'append_to_log': False, 16:48:06 INFO - 'base_work_dir': '/builds/slave/test', 16:48:06 INFO - 'blob_upload_branch': 'Autoland', 16:48:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:48:06 INFO - 'branch': 'Autoland', 16:48:06 INFO - 'buildbot_json_path': 'buildprops.json', 16:48:06 INFO - 'config_files': ('talos/mac_config.py',), 16:48:06 INFO - 'default_actions': ('clobber', 16:48:06 INFO - 'read-buildbot-config', 16:48:06 INFO - 'download-and-extract', 16:48:06 INFO - 'populate-webroot', 16:48:06 INFO - 'create-virtualenv', 16:48:06 INFO - 'install', 16:48:06 INFO - 'run-tests'), 16:48:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:48:06 INFO - 'download_minidump_stackwalk': True, 16:48:06 INFO - 'download_symbols': 'ondemand', 16:48:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:48:06 INFO - 'tooltool.py': '/tools/tooltool.py', 16:48:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:48:06 INFO - '/tools/misc-python/virtualenv.py')}, 16:48:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:48:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:48:06 INFO - 'installer_path': 'installer.exe', 16:48:06 INFO - 'log_level': 'info', 16:48:06 INFO - 'log_name': 'talos', 16:48:06 INFO - 'log_to_console': True, 16:48:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:48:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:48:06 INFO - 'opt_config_files': (), 16:48:06 INFO - 'pip_index': False, 16:48:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:48:06 INFO - 'cmd': ('bash', 16:48:06 INFO - '-c', 16:48:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:48:06 INFO - 'enabled': True, 16:48:06 INFO - 'halt_on_failure': False, 16:48:06 INFO - 'name': 'check_screen_resolution'},), 16:48:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:48:06 INFO - 'cmd': ('bash', 16:48:06 INFO - '-c', 16:48:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:48:06 INFO - 'enabled': True, 16:48:06 INFO - 'halt_on_failure': False, 16:48:06 INFO - 'name': 'check_screen_resolution'},), 16:48:06 INFO - 'run_cmd_checks_enabled': True, 16:48:06 INFO - 'sps_profile': False, 16:48:06 INFO - 'sps_profile_interval': 0, 16:48:06 INFO - 'suite': 'dromaeojs', 16:48:06 INFO - 'system_bits': '32', 16:48:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:48:06 INFO - 'title': 't-yosemite-r7-0166', 16:48:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:48:06 INFO - 'use_talos_json': True, 16:48:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:48:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:48:06 INFO - 'work_dir': 'build'} 16:48:06 INFO - ##### 16:48:06 INFO - ##### Running clobber step. 16:48:06 INFO - ##### 16:48:06 INFO - Running pre-action listener: _resource_record_pre_action 16:48:06 INFO - Running main action method: clobber 16:48:06 INFO - rmtree: /builds/slave/test/build 16:48:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:48:08 INFO - Running post-action listener: _resource_record_post_action 16:48:08 INFO - ##### 16:48:08 INFO - ##### Finished clobber step (success) 16:48:08 INFO - ##### 16:48:08 INFO - ##### 16:48:08 INFO - ##### Running read-buildbot-config step. 16:48:08 INFO - ##### 16:48:08 INFO - Running pre-action listener: _resource_record_pre_action 16:48:08 INFO - Running main action method: read_buildbot_config 16:48:08 INFO - Using buildbot properties: 16:48:08 INFO - { 16:48:08 INFO - "project": "", 16:48:08 INFO - "product": "firefox", 16:48:08 INFO - "script_repo_revision": "production", 16:48:08 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 16:48:08 INFO - "repository": "", 16:48:08 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 16:48:08 INFO - "buildid": "20160707152724", 16:48:08 INFO - "pgo_build": "False", 16:48:08 INFO - "basedir": "/builds/slave/test", 16:48:08 INFO - "buildnumber": 41, 16:48:08 INFO - "slavename": "t-yosemite-r7-0166", 16:48:08 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 16:48:08 INFO - "platform": "yosemite_r7", 16:48:08 INFO - "scheduler": "tests-autoland-macosx64-talos", 16:48:08 INFO - "branch": "autoland", 16:48:08 INFO - "repo_path": "integration/autoland", 16:48:08 INFO - "revision": "9a123c6c6dbf1715bc50db5103ded53f7865b3a0", 16:48:08 INFO - "stage_platform": "macosx64", 16:48:08 INFO - "builduid": "ffb37809d7b74efc9bc7ac8f068d5ed5", 16:48:08 INFO - "slavebuilddir": "test" 16:48:08 INFO - } 16:48:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/TZTwsMr1QTiS-8VvUsMgDA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 16:48:08 INFO - Running post-action listener: _resource_record_post_action 16:48:08 INFO - ##### 16:48:08 INFO - ##### Finished read-buildbot-config step (success) 16:48:08 INFO - ##### 16:48:08 INFO - ##### 16:48:08 INFO - ##### Running download-and-extract step. 16:48:08 INFO - ##### 16:48:08 INFO - Running pre-action listener: _resource_record_pre_action 16:48:08 INFO - Running main action method: download_and_extract 16:48:08 INFO - mkdir: /builds/slave/test/build/tests 16:48:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:48:08 INFO - https://queue.taskcluster.net/v1/task/TZTwsMr1QTiS-8VvUsMgDA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 16:48:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZTwsMr1QTiS-8VvUsMgDA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 16:48:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZTwsMr1QTiS-8VvUsMgDA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 16:48:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZTwsMr1QTiS-8VvUsMgDA/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 16:48:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZTwsMr1QTiS-8VvUsMgDA/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 16:48:38 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZTwsMr1QTiS-8VvUsMgDA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json: timed out 16:48:38 INFO - retry: attempt #1 caught exception: timed out 16:48:38 INFO - retry: Failed, sleeping 30 seconds before retrying 16:49:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZTwsMr1QTiS-8VvUsMgDA/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 #2 16:49:10 INFO - Downloaded 1199 bytes. 16:49:10 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 16:49:10 INFO - Using the following test package requirements: 16:49:10 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 16:49:10 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:49:10 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 16:49:10 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:49:10 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 16:49:10 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:49:10 INFO - u'jsshell-mac.zip'], 16:49:10 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:49:10 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 16:49:10 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 16:49:10 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:49:10 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 16:49:10 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:49:10 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 16:49:10 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:49:10 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 16:49:10 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 16:49:10 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 16:49:10 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:49:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:49:10 INFO - https://queue.taskcluster.net/v1/task/TZTwsMr1QTiS-8VvUsMgDA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:49:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZTwsMr1QTiS-8VvUsMgDA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:49:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZTwsMr1QTiS-8VvUsMgDA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:49:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZTwsMr1QTiS-8VvUsMgDA/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 16:49:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZTwsMr1QTiS-8VvUsMgDA/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 16:49:12 INFO - Downloaded 18806417 bytes. 16:49:12 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'] 16:49:12 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 16:49:15 INFO - Return code: 0 16:49:15 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 16:49:15 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')]} 16:49:15 INFO - https://queue.taskcluster.net/v1/task/TZTwsMr1QTiS-8VvUsMgDA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:49:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZTwsMr1QTiS-8VvUsMgDA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:49:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZTwsMr1QTiS-8VvUsMgDA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 16:49:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZTwsMr1QTiS-8VvUsMgDA/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 16:49:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZTwsMr1QTiS-8VvUsMgDA/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 16:49:20 INFO - Downloaded 18806417 bytes. 16:49:20 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'] 16:49:20 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 16:49:23 INFO - Return code: 0 16:49:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:49:23 INFO - https://queue.taskcluster.net/v1/task/TZTwsMr1QTiS-8VvUsMgDA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 16:49:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZTwsMr1QTiS-8VvUsMgDA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 16:49:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZTwsMr1QTiS-8VvUsMgDA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 16:49:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZTwsMr1QTiS-8VvUsMgDA/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 16:49:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZTwsMr1QTiS-8VvUsMgDA/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 16:49:26 INFO - Downloaded 14403333 bytes. 16:49: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'] 16:49: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 16:49:26 INFO - Return code: 0 16:49: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')]} 16:49:26 INFO - https://queue.taskcluster.net/v1/task/TZTwsMr1QTiS-8VvUsMgDA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 16:49:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZTwsMr1QTiS-8VvUsMgDA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 16:49:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZTwsMr1QTiS-8VvUsMgDA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 16:49:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZTwsMr1QTiS-8VvUsMgDA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 16:49:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZTwsMr1QTiS-8VvUsMgDA/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 16:49:29 INFO - Downloaded 112431760 bytes. 16:49:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TZTwsMr1QTiS-8VvUsMgDA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 16:49:29 INFO - mkdir: /builds/slave/test/properties 16:49:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:49:29 INFO - Writing to file /builds/slave/test/properties/build_url 16:49:29 INFO - Contents: 16:49:29 INFO - build_url:https://queue.taskcluster.net/v1/task/TZTwsMr1QTiS-8VvUsMgDA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 16:49:30 INFO - Running post-action listener: _resource_record_post_action 16:49:30 INFO - Running post-action listener: set_extra_try_arguments 16:49:30 INFO - ##### 16:49:30 INFO - ##### Finished download-and-extract step (success) 16:49:30 INFO - ##### 16:49:30 INFO - ##### 16:49:30 INFO - ##### Running populate-webroot step. 16:49:30 INFO - ##### 16:49:30 INFO - Running pre-action listener: _resource_record_pre_action 16:49:30 INFO - Running main action method: populate_webroot 16:49:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:49:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:49:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:49:30 INFO - '--tppagecycles', 16:49:30 INFO - '7'], 16:49:30 INFO - 'tests': ['tsvgm']}}, 16:49:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:49:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:49:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:49:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:49:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:49:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:49:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:49:30 INFO - 'talos_options': ['--disable-e10s'], 16:49:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:49:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:49:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:49:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:49:30 INFO - 'talos_options': ['--disable-e10s'], 16:49:30 INFO - 'tests': ['damp', 'tps']}, 16:49:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:49:30 INFO - 'tests': ['damp', 'tps']}, 16:49:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:49:30 INFO - 'tests': ['dromaeo_dom']}, 16:49:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:49:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:49:30 INFO - 'tests': ['basic_compositor_video']}, 16:49:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:49:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:49:30 INFO - 'tests': ['a11yr', 16:49:30 INFO - 'ts_paint', 16:49:30 INFO - 'tpaint', 16:49:30 INFO - 'sessionrestore', 16:49:30 INFO - 'sessionrestore_no_auto_restore', 16:49:30 INFO - 'tabpaint']}, 16:49:30 INFO - 'other-e10s': {'tests': ['a11yr', 16:49:30 INFO - 'ts_paint', 16:49:30 INFO - 'tpaint', 16:49:30 INFO - 'sessionrestore', 16:49:30 INFO - 'sessionrestore_no_auto_restore', 16:49:30 INFO - 'tabpaint']}, 16:49:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:49:30 INFO - 'ts_paint', 16:49:30 INFO - 'tpaint', 16:49:30 INFO - 'sessionrestore', 16:49:30 INFO - 'sessionrestore_no_auto_restore', 16:49:30 INFO - 'tabpaint']}, 16:49:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:49:30 INFO - 'ts_paint', 16:49:30 INFO - 'tpaint', 16:49:30 INFO - 'sessionrestore', 16:49:30 INFO - 'sessionrestore_no_auto_restore', 16:49:30 INFO - 'tabpaint']}, 16:49:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:49:30 INFO - 'tests': ['a11yr', 16:49:30 INFO - 'ts_paint', 16:49:30 INFO - 'tpaint', 16:49:30 INFO - 'sessionrestore', 16:49:30 INFO - 'sessionrestore_no_auto_restore', 16:49:30 INFO - 'tabpaint']}, 16:49:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:49:30 INFO - 'tests': ['a11yr', 16:49:30 INFO - 'ts_paint', 16:49:30 INFO - 'tpaint', 16:49:30 INFO - 'sessionrestore', 16:49:30 INFO - 'sessionrestore_no_auto_restore', 16:49:30 INFO - 'tabpaint']}, 16:49:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:49:30 INFO - 'tests': ['tsvgx', 16:49:30 INFO - 'tsvgr_opacity', 16:49:30 INFO - 'tart', 16:49:30 INFO - 'tscrollx', 16:49:30 INFO - 'cart']}, 16:49:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:49:30 INFO - 'tsvgr_opacity', 16:49:30 INFO - 'tart', 16:49:30 INFO - 'tscrollx', 16:49:30 INFO - 'cart']}, 16:49:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:49:30 INFO - 'talos_options': ['--disable-e10s'], 16:49:30 INFO - 'tests': ['tp5o']}, 16:49:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:49:30 INFO - 'tests': ['tp5o']}, 16:49:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:49:30 INFO - 'talos_options': ['--disable-e10s', 16:49:30 INFO - '--xperf_path', 16:49:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:49:30 INFO - 'tests': ['tp5n']}, 16:49:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:49:30 INFO - 'talos_options': ['--xperf_path', 16:49:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:49:30 INFO - 'tests': ['tp5n']}}, 16:49:30 INFO - 'talos.zip': {'path': '', 16:49:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:49:30 INFO - Running post-action listener: _resource_record_post_action 16:49:30 INFO - ##### 16:49:30 INFO - ##### Finished populate-webroot step (success) 16:49:30 INFO - ##### 16:49:30 INFO - ##### 16:49:30 INFO - ##### Running create-virtualenv step. 16:49:30 INFO - ##### 16:49:30 INFO - Running pre-action listener: _resource_record_pre_action 16:49:30 INFO - Running main action method: create_virtualenv 16:49:30 INFO - Creating virtualenv /builds/slave/test/build/venv 16:49:30 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 16:49:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:49:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:49:31 INFO - Using real prefix '/tools/python27' 16:49:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:49:31 INFO - Installing distribute.............................................................................................................................................................................................done. 16:49:34 INFO - Installing pip.................done. 16:49:34 INFO - Return code: 0 16:49:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:49: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')]} 16:49:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:49:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:49: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')]} 16:49:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:49:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:49: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 0x1055eba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105680618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4615d30d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10554fd30>, '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 0x1052b4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105547b20>, '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.lr5pYTCItg/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.FC62Ja0KYP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 16:49: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 16:49: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 16:49:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lr5pYTCItg/Render', 16:49:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:49:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:49:34 INFO - 'HOME': '/Users/cltbld', 16:49:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:49:34 INFO - 'LOGNAME': 'cltbld', 16:49:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:49:34 INFO - 'MOZ_NO_REMOTE': '1', 16:49:34 INFO - 'NO_EM_RESTART': '1', 16:49:34 INFO - 'PAGER': '/bin/cat', 16:49:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:49:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:49:34 INFO - 'PWD': '/builds/slave/test', 16:49:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:49:34 INFO - 'SHELL': '/bin/bash', 16:49:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FC62Ja0KYP/Listeners', 16:49:34 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 16:49:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:49:34 INFO - 'USER': 'cltbld', 16:49:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:49:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:49:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:49:34 INFO - 'XPC_FLAGS': '0x0', 16:49:34 INFO - 'XPC_SERVICE_NAME': '0', 16:49:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:49:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:49:34 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 16:49:34 INFO - Cleaning up... 16:49:34 INFO - Return code: 0 16:49:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:49: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')]} 16:49:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:49:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:49: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')]} 16:49:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:49:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:49: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', '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 0x1055eba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105680618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4615d30d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10554fd30>, '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 0x1052b4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105547b20>, '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.lr5pYTCItg/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.FC62Ja0KYP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 16:49: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', 'psutil>=3.1.1'] in /builds/slave/test/build 16:49: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 psutil>=3.1.1 16:49:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lr5pYTCItg/Render', 16:49:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:49:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:49:34 INFO - 'HOME': '/Users/cltbld', 16:49:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:49:34 INFO - 'LOGNAME': 'cltbld', 16:49:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:49:34 INFO - 'MOZ_NO_REMOTE': '1', 16:49:34 INFO - 'NO_EM_RESTART': '1', 16:49:34 INFO - 'PAGER': '/bin/cat', 16:49:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:49:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:49:34 INFO - 'PWD': '/builds/slave/test', 16:49:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:49:34 INFO - 'SHELL': '/bin/bash', 16:49:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FC62Ja0KYP/Listeners', 16:49:34 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 16:49:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:49:34 INFO - 'USER': 'cltbld', 16:49:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:49:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:49:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:49:34 INFO - 'XPC_FLAGS': '0x0', 16:49:34 INFO - 'XPC_SERVICE_NAME': '0', 16:49:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:49:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:49:35 INFO - Downloading/unpacking psutil>=3.1.1 16:49: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 16:49: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 16:49: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 16:49: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 16:49: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 16:49: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 16:49:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:49:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:49:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:49:38 INFO - Installing collected packages: psutil 16:49:38 INFO - Running setup.py install for psutil 16:49:38 INFO - building 'psutil._psutil_osx' extension 16:49: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 16:49:39 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 16:49:39 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 16:49:39 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 16:49:39 INFO - building 'psutil._psutil_posix' extension 16:49:39 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 16:49:39 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:49:39 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:49:39 INFO - ^ 16:49:39 INFO - 1 warning generated. 16:49:39 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 16:49:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:49:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:49:39 INFO - Successfully installed psutil 16:49:39 INFO - Cleaning up... 16:49:39 INFO - Return code: 0 16:49:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:49: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')]} 16:49:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:49:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:49: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')]} 16:49:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:49:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:49: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 0x1055eba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105680618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4615d30d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10554fd30>, '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 0x1052b4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105547b20>, '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.lr5pYTCItg/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.FC62Ja0KYP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 16:49: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 16:49: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 16:49:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lr5pYTCItg/Render', 16:49:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:49:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:49:39 INFO - 'HOME': '/Users/cltbld', 16:49:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:49:39 INFO - 'LOGNAME': 'cltbld', 16:49:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:49:39 INFO - 'MOZ_NO_REMOTE': '1', 16:49:39 INFO - 'NO_EM_RESTART': '1', 16:49:39 INFO - 'PAGER': '/bin/cat', 16:49:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:49:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:49:39 INFO - 'PWD': '/builds/slave/test', 16:49:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:49:39 INFO - 'SHELL': '/bin/bash', 16:49:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FC62Ja0KYP/Listeners', 16:49:39 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 16:49:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:49:39 INFO - 'USER': 'cltbld', 16:49:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:49:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:49:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:49:39 INFO - 'XPC_FLAGS': '0x0', 16:49:39 INFO - 'XPC_SERVICE_NAME': '0', 16:49:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:49:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:49:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:49:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:49:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:49:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:49:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:49:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:49:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:49:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:49:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:49:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:49:42 INFO - Installing collected packages: mozsystemmonitor 16:49:42 INFO - Running setup.py install for mozsystemmonitor 16:49:42 INFO - Successfully installed mozsystemmonitor 16:49:42 INFO - Cleaning up... 16:49:42 INFO - Return code: 0 16:49:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:49: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')]} 16:49:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:49:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:49: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')]} 16:49:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:49:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:49: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 0x1055eba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105680618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4615d30d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10554fd30>, '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 0x1052b4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105547b20>, '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.lr5pYTCItg/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.FC62Ja0KYP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 16:49: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 16:49: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 16:49:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lr5pYTCItg/Render', 16:49:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:49:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:49:42 INFO - 'HOME': '/Users/cltbld', 16:49:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:49:42 INFO - 'LOGNAME': 'cltbld', 16:49:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:49:42 INFO - 'MOZ_NO_REMOTE': '1', 16:49:42 INFO - 'NO_EM_RESTART': '1', 16:49:42 INFO - 'PAGER': '/bin/cat', 16:49:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:49:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:49:42 INFO - 'PWD': '/builds/slave/test', 16:49:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:49:42 INFO - 'SHELL': '/bin/bash', 16:49:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FC62Ja0KYP/Listeners', 16:49:42 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 16:49:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:49:42 INFO - 'USER': 'cltbld', 16:49:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:49:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:49:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:49:42 INFO - 'XPC_FLAGS': '0x0', 16:49:42 INFO - 'XPC_SERVICE_NAME': '0', 16:49:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:49:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:49:43 INFO - Downloading/unpacking jsonschema==2.5.1 16:49:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:49:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:49:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:49:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:49:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:49:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:49:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:49:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:49: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 16:49: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 16:49: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 16:49: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 16:49: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 16:49: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 16:49:45 INFO - Downloading functools32-3.2.3-2.tar.gz 16:49:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:49:46 INFO - warning: no files found matching '*.txt' 16:49:46 INFO - no previously-included directories found matching 'build' 16:49:46 INFO - no previously-included directories found matching 'dist' 16:49:46 INFO - no previously-included directories found matching '.git*' 16:49:46 INFO - Installing collected packages: jsonschema, functools32 16:49:46 INFO - Running setup.py install for functools32 16:49:46 INFO - warning: no files found matching '*.txt' 16:49:46 INFO - no previously-included directories found matching 'build' 16:49:46 INFO - no previously-included directories found matching 'dist' 16:49:46 INFO - no previously-included directories found matching '.git*' 16:49:46 INFO - Successfully installed jsonschema functools32 16:49:46 INFO - Cleaning up... 16:49:46 INFO - Return code: 0 16:49:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:49:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:49:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:49:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:49:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:49:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:49:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:49:46 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 0x1055eba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105680618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4615d30d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10554fd30>, '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 0x1052b4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105547b20>, '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.lr5pYTCItg/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.FC62Ja0KYP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 16:49:46 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 16:49:46 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 16:49:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lr5pYTCItg/Render', 16:49:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:49:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:49:46 INFO - 'HOME': '/Users/cltbld', 16:49:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:49:46 INFO - 'LOGNAME': 'cltbld', 16:49:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:49:46 INFO - 'MOZ_NO_REMOTE': '1', 16:49:46 INFO - 'NO_EM_RESTART': '1', 16:49:46 INFO - 'PAGER': '/bin/cat', 16:49:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:49:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:49:46 INFO - 'PWD': '/builds/slave/test', 16:49:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:49:46 INFO - 'SHELL': '/bin/bash', 16:49:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FC62Ja0KYP/Listeners', 16:49:46 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 16:49:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:49:46 INFO - 'USER': 'cltbld', 16:49:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:49:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:49:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:49:46 INFO - 'XPC_FLAGS': '0x0', 16:49:46 INFO - 'XPC_SERVICE_NAME': '0', 16:49:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:49:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:49:46 INFO - Downloading/unpacking blobuploader==1.2.4 16:49:46 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 16:49:46 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 16:49:46 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 16:49:46 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 16:49:46 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 16:49:46 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 16:49:49 INFO - Downloading blobuploader-1.2.4.tar.gz 16:49:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:49:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:49: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 16:49: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 16:49: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 16:49: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 16:49: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 16:49: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 16:49:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:49:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:49: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 16:49: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 16:49: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 16:49: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 16:49: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 16:49: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 16:49:50 INFO - Downloading docopt-0.6.1.tar.gz 16:49:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:49:50 INFO - Installing collected packages: blobuploader, requests, docopt 16:49:50 INFO - Running setup.py install for blobuploader 16:49:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:49:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:49:50 INFO - Running setup.py install for requests 16:49:50 INFO - Running setup.py install for docopt 16:49:51 INFO - Successfully installed blobuploader requests docopt 16:49:51 INFO - Cleaning up... 16:49:51 INFO - Return code: 0 16:49:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:49:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:49:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:49:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:49:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:49:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:49:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:49:51 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 0x1055eba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105680618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4615d30d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10554fd30>, '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 0x1052b4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105547b20>, '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.lr5pYTCItg/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.FC62Ja0KYP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 16:49:51 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 16:49:51 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 16:49:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lr5pYTCItg/Render', 16:49:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:49:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:49:51 INFO - 'HOME': '/Users/cltbld', 16:49:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:49:51 INFO - 'LOGNAME': 'cltbld', 16:49:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:49:51 INFO - 'MOZ_NO_REMOTE': '1', 16:49:51 INFO - 'NO_EM_RESTART': '1', 16:49:51 INFO - 'PAGER': '/bin/cat', 16:49:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:49:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:49:51 INFO - 'PWD': '/builds/slave/test', 16:49:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:49:51 INFO - 'SHELL': '/bin/bash', 16:49:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FC62Ja0KYP/Listeners', 16:49:51 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 16:49:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:49:51 INFO - 'USER': 'cltbld', 16:49:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:49:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:49:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:49:51 INFO - 'XPC_FLAGS': '0x0', 16:49:51 INFO - 'XPC_SERVICE_NAME': '0', 16:49:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:49:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:49:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:49:51 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-Ho_nQk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:49:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:49:51 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-kdsro7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:49:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:49:51 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-fWbgGb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:49:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:49:51 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-Yw0leW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:49:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:49:51 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-3co4RL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:49:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:49:51 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-uBfZzp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:49:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:49:52 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-LgKaPs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:49:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:49:52 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-FqYzGA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:49:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:49:52 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-zL90tX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:49:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:49:52 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-TJSjhC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:49:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:49:52 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-6J6lCp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:49:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:49:52 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-NGU2V9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:49:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:49:52 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-3fJnif-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:49:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:49:52 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-FHRS8N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:49:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:49:52 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-3zM7xX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:49:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:49:53 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-wKovxD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:49:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:49:53 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-WBrmZK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:49:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:49:53 INFO - Running setup.py install for manifestparser 16:49:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:49:53 INFO - Running setup.py install for mozcrash 16:49:53 INFO - Running setup.py install for mozdebug 16:49:53 INFO - Running setup.py install for mozdevice 16:49:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:49:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:49:53 INFO - Running setup.py install for mozfile 16:49:53 INFO - Running setup.py install for mozhttpd 16:49:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:49:54 INFO - Running setup.py install for mozinfo 16:49:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:49:54 INFO - Running setup.py install for mozInstall 16:49:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:49:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:49:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:49:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:49:54 INFO - Running setup.py install for mozleak 16:49:54 INFO - Running setup.py install for mozlog 16:49:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:49:54 INFO - Running setup.py install for moznetwork 16:49:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:49:54 INFO - Running setup.py install for mozprocess 16:49:55 INFO - Running setup.py install for mozprofile 16:49:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:49:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:49:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:49:55 INFO - Running setup.py install for mozrunner 16:49:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:49:55 INFO - Running setup.py install for mozscreenshot 16:49:55 INFO - Running setup.py install for moztest 16:49:55 INFO - Running setup.py install for mozversion 16:49:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:49:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:49:55 INFO - Cleaning up... 16:49:55 INFO - Return code: 0 16:49:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:49: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')]} 16:49:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:49:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:49: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')]} 16:49:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:49:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:49: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 0x1055eba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105680618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4615d30d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10554fd30>, '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 0x1052b4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105547b20>, '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.lr5pYTCItg/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.FC62Ja0KYP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 16:49: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 16:49: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 16:49:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lr5pYTCItg/Render', 16:49:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:49:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:49:55 INFO - 'HOME': '/Users/cltbld', 16:49:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:49:55 INFO - 'LOGNAME': 'cltbld', 16:49:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:49:55 INFO - 'MOZ_NO_REMOTE': '1', 16:49:55 INFO - 'NO_EM_RESTART': '1', 16:49:55 INFO - 'PAGER': '/bin/cat', 16:49:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:49:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:49:55 INFO - 'PWD': '/builds/slave/test', 16:49:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:49:55 INFO - 'SHELL': '/bin/bash', 16:49:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FC62Ja0KYP/Listeners', 16:49:55 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 16:49:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:49:55 INFO - 'USER': 'cltbld', 16:49:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:49:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:49:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:49:55 INFO - 'XPC_FLAGS': '0x0', 16:49:55 INFO - 'XPC_SERVICE_NAME': '0', 16:49:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:49:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:49:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:49:56 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-zyPgQn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:49:56 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)) 16:49:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:49:56 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-V4YNbr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:49:56 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)) 16:49:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:49:56 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-Dtl7wS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:49:56 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)) 16:49:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:49:56 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-HdtC0Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:49:56 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)) 16:49:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:49:56 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-xA4GbS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:49:56 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)) 16:49:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:49:56 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-gNHmQW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:49: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)) 16:49:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:49:56 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-kPFd9V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:49: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)) 16:49:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:49:56 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-tdTYyK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:49: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)) 16:49:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:49:56 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-WygfBa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:49:57 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)) 16:49:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:49:57 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-zZgb1V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:49:57 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)) 16:49:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:49:57 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-u_jiq8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:49:57 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)) 16:49:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:49:57 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-nIIwKU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:49:57 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)) 16:49:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:49:57 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-u7ZTHE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:49:57 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)) 16:49:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:49:57 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-syvvmS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:49:57 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)) 16:49:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:49:57 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-eZmYI0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:49:57 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)) 16:49:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:49:57 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-hlBdq3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:49: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)) 16:49:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:49:57 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-200iCt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:49: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)) 16:49: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)) 16:49: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)) 16:49: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)) 16:49: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)) 16:49: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)) 16:49:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:49: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 16:49: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 16:49: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 16:49: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 16:49: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 16:49: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 16:50:00 INFO - Downloading blessings-1.6.tar.gz 16:50:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:50:00 INFO - Installing collected packages: blessings 16:50:00 INFO - Running setup.py install for blessings 16:50:00 INFO - Successfully installed blessings 16:50:00 INFO - Cleaning up... 16:50:00 INFO - Return code: 0 16:50:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:50:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:50:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:50:00 INFO - Reading from file tmpfile_stdout 16:50:00 INFO - Current package versions: 16:50:00 INFO - blessings == 1.6 16:50:00 INFO - blobuploader == 1.2.4 16:50:00 INFO - docopt == 0.6.1 16:50:00 INFO - functools32 == 3.2.3-2 16:50:00 INFO - jsonschema == 2.5.1 16:50:00 INFO - manifestparser == 1.1 16:50:00 INFO - mozInstall == 1.12 16:50:00 INFO - mozcrash == 0.17 16:50:00 INFO - mozdebug == 0.1 16:50:00 INFO - mozdevice == 0.48 16:50:00 INFO - mozfile == 1.2 16:50:00 INFO - mozhttpd == 0.7 16:50:00 INFO - mozinfo == 0.9 16:50:00 INFO - mozleak == 0.1 16:50:00 INFO - mozlog == 3.2 16:50:00 INFO - moznetwork == 0.27 16:50:00 INFO - mozprocess == 0.23 16:50:00 INFO - mozprofile == 0.28 16:50:00 INFO - mozrunner == 6.12 16:50:00 INFO - mozscreenshot == 0.1 16:50:00 INFO - mozsystemmonitor == 0.3 16:50:00 INFO - moztest == 0.7 16:50:00 INFO - mozversion == 1.4 16:50:00 INFO - psutil == 3.1.1 16:50:00 INFO - requests == 1.2.3 16:50:00 INFO - wsgiref == 0.1.2 16:50:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:50: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')]} 16:50:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:50:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:50: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')]} 16:50:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:50:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:50: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 0x1055eba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105680618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4615d30d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10554fd30>, '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 0x1052b4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105547b20>, '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.lr5pYTCItg/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.FC62Ja0KYP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 16:50: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 16:50: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 16:50:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lr5pYTCItg/Render', 16:50:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:50:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:50:00 INFO - 'HOME': '/Users/cltbld', 16:50:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:50:00 INFO - 'LOGNAME': 'cltbld', 16:50:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:50:00 INFO - 'MOZ_NO_REMOTE': '1', 16:50:00 INFO - 'NO_EM_RESTART': '1', 16:50:00 INFO - 'PAGER': '/bin/cat', 16:50:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:50:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:50:00 INFO - 'PWD': '/builds/slave/test', 16:50:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:50:00 INFO - 'SHELL': '/bin/bash', 16:50:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FC62Ja0KYP/Listeners', 16:50:00 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 16:50:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:50:00 INFO - 'USER': 'cltbld', 16:50:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:50:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:50:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:50:00 INFO - 'XPC_FLAGS': '0x0', 16:50:00 INFO - 'XPC_SERVICE_NAME': '0', 16:50:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:50:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:50:01 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)) 16:50:01 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)) 16:50:01 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)) 16:50:01 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)) 16:50:01 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)) 16:50:01 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)) 16:50:01 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)) 16:50:01 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)) 16:50:01 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)) 16:50:01 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)) 16:50:01 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)) 16:50:01 INFO - Cleaning up... 16:50:01 INFO - Return code: 0 16:50:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:50:01 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')]} 16:50:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:50:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:50:01 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')]} 16:50:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:50:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:50:01 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 0x1055eba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105680618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff4615d30d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10554fd30>, '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 0x1052b4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105547b20>, '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.lr5pYTCItg/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.FC62Ja0KYP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 16:50:01 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 16:50:01 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 16:50:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lr5pYTCItg/Render', 16:50:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:50:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:50:01 INFO - 'HOME': '/Users/cltbld', 16:50:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:50:01 INFO - 'LOGNAME': 'cltbld', 16:50:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:50:01 INFO - 'MOZ_NO_REMOTE': '1', 16:50:01 INFO - 'NO_EM_RESTART': '1', 16:50:01 INFO - 'PAGER': '/bin/cat', 16:50:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:50:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:50:01 INFO - 'PWD': '/builds/slave/test', 16:50:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:50:01 INFO - 'SHELL': '/bin/bash', 16:50:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FC62Ja0KYP/Listeners', 16:50:01 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 16:50:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:50:01 INFO - 'USER': 'cltbld', 16:50:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:50:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:50:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:50:01 INFO - 'XPC_FLAGS': '0x0', 16:50:01 INFO - 'XPC_SERVICE_NAME': '0', 16:50:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:50:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:50:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:50:01 INFO - Cleaning up... 16:50:01 INFO - Return code: 0 16:50:01 INFO - Running post-action listener: _resource_record_post_action 16:50:01 INFO - Running post-action listener: _start_resource_monitoring 16:50:01 INFO - Starting resource monitoring. 16:50:01 INFO - ##### 16:50:01 INFO - ##### Finished create-virtualenv step (success) 16:50:01 INFO - ##### 16:50:01 INFO - ##### 16:50:01 INFO - ##### Running install step. 16:50:01 INFO - ##### 16:50:01 INFO - Running pre-action listener: _resource_record_pre_action 16:50:01 INFO - Running main action method: install 16:50:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:50:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:50:01 INFO - Reading from file tmpfile_stdout 16:50:01 INFO - Detecting whether we're running mozinstall >=1.0... 16:50:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:50:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:50:01 INFO - Reading from file tmpfile_stdout 16:50:01 INFO - Output received: 16:50:01 INFO - Usage: mozinstall [options] installer 16:50:01 INFO - Options: 16:50:01 INFO - -h, --help show this help message and exit 16:50:01 INFO - -d DEST, --destination=DEST 16:50:01 INFO - Directory to install application into. [default: 16:50:01 INFO - "/builds/slave/test"] 16:50:01 INFO - --app=APP Application being installed. [default: firefox] 16:50:01 INFO - mkdir: /builds/slave/test/build/application 16:50: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'] 16:50: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 16:50:36 INFO - Reading from file tmpfile_stdout 16:50:36 INFO - Output received: 16:50:36 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:50:36 INFO - Running post-action listener: _resource_record_post_action 16:50:36 INFO - ##### 16:50:36 INFO - ##### Finished install step (success) 16:50:36 INFO - ##### 16:50:36 INFO - ##### 16:50:36 INFO - ##### Running run-tests step. 16:50:36 INFO - ##### 16:50:36 INFO - Running pre-action listener: _resource_record_pre_action 16:50:36 INFO - Running main action method: run_tests 16:50:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:50:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:50:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:50:36 INFO - 2016-07-07 16:50:36.434 screenresolution[1642:8552] starting screenresolution argv=screenresolution get 16:50:36 INFO - 2016-07-07 16:50:36.437 screenresolution[1642:8552] Display 0: 1600x1200x32@60 16:50:36 INFO - 2016-07-07 16:50:36.443 screenresolution[1643:8557] starting screenresolution argv=screenresolution list 16:50:36 INFO - Available Modes on Display 0 16:50:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:50:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:50:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:50:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:50:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:50:36 INFO - Intel Iris: 16:50:36 INFO - Chipset Model: Intel Iris 16:50:36 INFO - Type: GPU 16:50:36 INFO - Bus: Built-In 16:50:36 INFO - VRAM (Dynamic, Max): 1536 MB 16:50:36 INFO - Vendor: Intel (0x8086) 16:50:36 INFO - Device ID: 0x0a2e 16:50:36 INFO - Revision ID: 0x0009 16:50:36 INFO - Displays: 16:50:36 INFO - Display: 16:50:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:50:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:50:36 INFO - Display Serial Number: 1600x1200 60 16:50:36 INFO - Main Display: Yes 16:50:36 INFO - Mirror: Off 16:50:36 INFO - Online: Yes 16:50:36 INFO - Rotation: Supported 16:50:36 INFO - Return code: 0 16:50:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:50:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:50:36 INFO - Python 2.7.3 16:50:36 INFO - Return code: 0 16:50:36 INFO - grabbing minidump binary from tooltool 16:50:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:50:36 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 0x10554fd30>, '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 0x1052b4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105547b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:50:36 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 16:50:36 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 16:50:36 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 16:50:36 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:50:36 INFO - Return code: 0 16:50:36 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:50:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:50:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:50:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:50:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:50:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TZTwsMr1QTiS-8VvUsMgDA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0166', '--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 16:50:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/TZTwsMr1QTiS-8VvUsMgDA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0166 --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 16:50:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lr5pYTCItg/Render', 16:50:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:50:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:50:36 INFO - 'HOME': '/Users/cltbld', 16:50:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:50:36 INFO - 'LOGNAME': 'cltbld', 16:50:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:50:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:50:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:50:36 INFO - 'MOZ_NO_REMOTE': '1', 16:50:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:50:36 INFO - 'NO_EM_RESTART': '1', 16:50:36 INFO - 'PAGER': '/bin/cat', 16:50:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:50:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:50:36 INFO - 'PWD': '/builds/slave/test', 16:50:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:50:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:50:36 INFO - 'SHELL': '/bin/bash', 16:50:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.FC62Ja0KYP/Listeners', 16:50:36 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 16:50:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:50:36 INFO - 'USER': 'cltbld', 16:50:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:50:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:50:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:50:36 INFO - 'XPC_FLAGS': '0x0', 16:50:36 INFO - 'XPC_SERVICE_NAME': '0', 16:50:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:50:36 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TZTwsMr1QTiS-8VvUsMgDA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0166', '--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 16:50:37 INFO - mozversion application_buildid: 20160707152724 16:50:37 INFO - mozversion application_changeset: 9a123c6c6dbf1715bc50db5103ded53f7865b3a0 16:50:37 INFO - mozversion application_display_name: Nightly 16:50:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:50:37 INFO - mozversion application_name: Firefox 16:50:37 INFO - mozversion application_remotingname: firefox 16:50:37 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 16:50:37 INFO - mozversion application_vendor: Mozilla 16:50:37 INFO - mozversion application_version: 50.0a1 16:50:37 INFO - mozversion platform_buildid: 20160707152724 16:50:37 INFO - mozversion platform_changeset: 9a123c6c6dbf1715bc50db5103ded53f7865b3a0 16:50:37 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 16:50:37 INFO - mozversion platform_version: 50.0a1 16:50:37 INFO - using testdate: 1467935437 16:50:37 INFO - actual date: 1467935437 16:50:37 INFO - starting webserver on 'localhost:49280' 16:50:37 INFO - SUITE-START | Running 2 tests 16:50:37 INFO - TEST-START | dromaeo_css 16:50:37 INFO - operating with platform_type : mac_ 16:50:37 INFO - Initialising browser for dromaeo_css test... 16:50:37 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/tmpfwGFfD/profile http://localhost:49280/getInfo.html) 16:50:39 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 16:50:39 INFO - PROCESS | 1653 | colorDepth:24 16:50:39 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 16:50:39 INFO - PROCESS | 1653 | __metrics 16:50:39 INFO - PROCESS | 1653 | 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" 16:50:39 INFO - PROCESS | 1653 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:50:40 INFO - TEST-INFO | 1653: exit 0 16:50:40 INFO - Browser initialized. 16:50:40 INFO - Running cycle 1/1 for dromaeo_css test... 16:50:40 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/tmpfwGFfD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:50:52 INFO - PROCESS | 1656 | RSS: Main: 243425280 16:50:52 INFO - PROCESS | 1656 | 16:51:43 INFO - PROCESS | 1656 | 1467935503541 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] 16:53:53 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49280/tests/dromaeo/cssquery-ext.html) 16:53:55 INFO - PROCESS | 1656 | RSS: Main: 336592896 16:53:55 INFO - PROCESS | 1656 | 16:56:58 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-ext.html (next: http://localhost:49280/tests/dromaeo/cssquery-jquery.html) 16:56:58 INFO - PROCESS | 1656 | RSS: Main: 375324672 16:56:58 INFO - PROCESS | 1656 | 17:00:01 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49280/tests/dromaeo/cssquery-mootools.html) 17:00:01 INFO - PROCESS | 1656 | RSS: Main: 374898688 17:00:01 INFO - PROCESS | 1656 | 17:03:04 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49280/tests/dromaeo/cssquery-prototype.html) 17:03:04 INFO - PROCESS | 1656 | RSS: Main: 372494336 17:03:04 INFO - PROCESS | 1656 | 17:06:08 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49280/tests/dromaeo/cssquery-yui.html) 17:06:08 INFO - PROCESS | 1656 | RSS: Main: 346501120 17:06:08 INFO - PROCESS | 1656 | 17:09:11 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-yui.html (next: http://localhost:49280/tests/dromaeo/cssquery-dojo.html) 17:09:12 INFO - PROCESS | 1656 | RSS: Main: 393125888 17:09:12 INFO - PROCESS | 1656 | 17:09:12 INFO - PROCESS | 1656 | __start_tp_report 17:09:12 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 17:09:12 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 17:09:12 INFO - PROCESS | 1656 | |i|pagename|runs| 17:09:12 INFO - PROCESS | 1656 | |0;dojo.html;3350.1959529712867;3363.7477189210817;3382.3404436134965;3383.7631365804396;3433.3476639438504;5525;5585.972070139654;5621.297337832771;5677.034904066309;5697.971510142449;5616.185653080301;5697.401772813857;5702.45826646469;5751.137329400592;5819.825405237839;5137.357830271216;5181.145111056671;5184.481551844823;5209.479052094779;5224.869378265555;5161.225816127573;5289;5289.867753306179;5319.468053194669;5321.095413779647;5258.106121959276;5266.631335806495;5281.973590132044;5290.92063619046;5321.042212401785;5099;5141.717205553693;5159.17453207485;5188.169892817131;5198.454162312941;5184.766685499161;5199.116150254428;5206.97396513015;5219.895602087937;5235.502627250406;5563.332400112013;5677.858053548653;5688.374278829326;5767.826965191051;5773.422657734235;6050.565225370921;6108.450239478424;6129.1725617041575;6135.049067394561;6136.0902143038675;6177.536684748662;6274.529410294246;6278.089676996809;6297.433231009231;6313.368663133696;5775.537956963517;5777.422257774231;5806.59353845231;5835.941640583625;5841.85395365115;6195.19462469876;6200.0699895015205;6238.1266622672865;6272.278687950853;6279;5142.357205349332;5997.610155339976;6068.848278792977;6127.571070025145;6129.264488261437;6161.568690191643;6253.187085678833;6271.749130034747;6272.686365681789;6278.089676996809;6191;6205.5345849061505;6212;6220.968895155495;6239.844003899939;6085.8478538036015;6125.75438176821;6133.916185115316;6140.780336713758;6213.130161777355;4881.085792553854;4901.877453063702;4906.607471402279;4923.163062279421;4950.950490495121;4393.165298593257;4408.096340250241;4423.646108311327;4440.222960981815;4444.155610434007;5596.2165296858475;5654.6324488908085;5703.058995265817;5714.342850572238;5775.27809023872;6282;6325.367463253638;6339.953907605193;6340.619562826245;6361.809145725636;5731.426857314319;5746.281714785652;5755.62588431759;5782.566307526952;5815.185873977646;5288.497592728685;5390.865228369322;5424.864378390572;5429.782808687687;5472.398036216013;6225.003999360262;6296.401841825111;6300.180976473029;6302.842428939313;6327.746890124344;6296.150019747275;6326.114343991753;6326.2092238470195;6342.524310676626;6345.555811093363;6199.070139479114;6202.596831205957;6232.439080482778;6259.96870015647;6337.8732425352155;6107.389261073857;6182;6199.969000154971;6227.439530442282;6235.095911092957;5143.974280128576;6277.2781130170615;6298.842528936813;6327.715252813727;6356.2054743157105;5594.328680558359;6024.819255422344;6069.423404776539;6082.513398927987;6096.603720758137;6073.605215660968;6076.635401875901;6078.118672792509;6083.756649734138;6112.32764395925;5732.3121225453215;5905.43898329658;5937.5249980000635;5943.910841337297;5971.104334349882;5987.700614969322;6032.819015429544;6089.817305480843;6102.206713127265;6115.205023346936;5282.630215884851;5686.943130568641;5885.941140588539;5940.584159108821;5945.524358051259 17:09:12 INFO - PROCESS | 1656 | |1;ext.html;480.8653576998441;509.57959683261316;532.191069574247;538.7360193505184;544.7793643574353;40003.79998100009;41343.37984930221;41539;41596.37605435917;43105.7844710776;42780.57219427805;43017.56982430175;43184;43248.13503729924;43318.56681433185;8603.096674849145;8738.432001919886;9575.95212023939;9739.513024348755;9748.463834489101;11535.077193824516;11803;11832.349220792852;11978.401079945968;11989.640310790646;11800.114991375682;11851;11868.169228154033;11868.28790272582;11887.049036077136;8599.538078526602;11578.363190024545;11693.473793679304;11776.116791240649;11829;11629;11706.122040846885;11719.531218751239;11721;11726.061915046776;40464.595354046374;40754.38868416976;40774.79612601948;40800.18399632021;40852.79573602143;2972.2717934106067;3426.3489936912156;3431.0564594736397;3478.6607156244777;3702.3520883845217;31614.209644759067;32024.637413071527;32048;32092;33000.834995824865;3237;3254.088855120546;3257.5439438478634;3276.1482014676117;3311.6853898879567;499202;499976;500588;501102.99999999633;501578.4921075371;58208.41791582115;58277.4172258272;58472.12291815626;58902.11646825301;59039.70480147572;33985.15037124092;34110.829445852614;34117.658823411446;61362;61687.691561541906;14223.502177423741;16812.83187168137;16901.577460563338;16936.068516231724;16955.321787128625;11678.591249306233;11863.991560717435;12586.81119783204;12780.808287875689;13010.609681709564;18992.620147596972;19056.71414928777;19123.80876191248;19160;19181.32865349706;8137.959310203411;8165.918340816635;8177.9182208178345;8193.71322003727;8247.917520824834;893.3300024981264;993.1508560181086;1065.9040686338267;1082.5723839083519;1090;29954.95157669471;29962.55056174159;30341.688377803486;30530.84734576313;30664.233394165323;1021.116734025063;1062.4422178356424;1071.9464026798628;1180.392098069495;1189.1972918280126;15546.611334716496;15681;15724.921375393049;15907.522774316789;16625.6674866502;695832;699202;701163;701223;703861;49978;59706.70146649326;59710.70144649336;59821;60050.69467705915;1246.2958428487875;1282.4677758730209;1349.3927732520601;1422.8363738170242;1448.66680663448;30858.074257772303;31077.223069423446;31088.84455577798;31876.681233188297;31947.520787187746;49661.50338496569;49982;50228;50326.245106322705;50403.73990650263;522272;522307;524931;525520;526535;23081;26063.60904586395;26179.607305890047;26384.60423093617;26424.207273781816;46038.769806150754;46288.768556157;46661.53338466708;46800.297995529414;47206.29190562213;37385;38646.48797828245;38934;39331;39579.01052473711;30410.54384184283;37627.68303109378;38002.619973799905;38501.22997540089;39040.2191956165;37252.62747372492;37861;37988.05029874275;38244.80877595594;38337.04157396087;31436;33200.83399582987;33820;33900.83049584736;34038;20960.161593536086;24891;24974.750252497244;25014.874925625983;25475.36311592225 17:09:12 INFO - PROCESS | 1656 | |2;jquery.html;359.33522982482395;374.50004244333815;384.1912773611548;386.37600275554985;405.6997821612008;15752.527424177279;15884.126373049477;20656.380308590728;20696.793032069676;21004.159833606675;21426.57146857062;21936.780632193673;22203.66694499584;22227.888860555715;22297.554048919013;5303.337082864642;5986.161937328767;6229.813105606816;6236.812895613139;6238.812835614939;5329.546988505962;6160.537959653022;6194.442500174983;6196.659183744915;6204.6587437690905;5855.941440585582;6027.397260273981;6028.63828170309;6073.878522429549;6108.816735497942;5733.627314224562;5956.910646340309;6032.698365081728;6040.305364883051;6054.819310234506;5809.7676092956235;5871.823845284648;5892.734826932799;5894.557908156906;5916.526677865803;13516.594502164951;16976;18558.44324670262;26599.335016624547;26656.733432665813;2137.4976880433087;2334.603117470017;2534.733852945433;2611.9216423507323;2879.3665393613373;9915.355501892354;9977.401355918595;10127.949360253226;10490.370577765358;10523.368597884151;1695.5337282247358;1777.7244527098321;1862.850971922243;1900.429871038683;1904.6952487602057;97163;120457.77253364825;121117.39441302825;121819;124176;9583.712488625353;11743.530258789553;11783.469743861548;11816.231944923553;11964.102692297938;14656.780148297785;14746.852531474762;14770.778438323434;14770.926145369205;14775.852241477447;8717.30261579072;8748.34387420946;8802.07578204292;8804.383693141419;8838.292936565187;8780.297576193889;8789.516576588348;8795;8848.823023539493;8922.73231803047;4611.400517932714;4619.1685496610935;4622.722636641813;4628.490866004737;4674.579287864109;1488.4939120699014;1882.9623407531772;1897.544589298586;1931.8647694661238;1959.4611481842608;723.605634928965;834.4742812028384;856.7429771068779;931.5249222896372;940.229012209995;16648.3340666372;20977.37067887966;21544.89227553852;21641.35075947724;21734.56530869374;935.4247138010074;948.6821914658528;962.2350231565922;966.6181858165985;969.336004836675;10918.399488028075;11352.48913798881;11385.715357116138;11398.544058237743;11440.828387574193;611304;613049;623927.8803605953;628992;629278;141728.29135854254;146905;147334;147782;148267;855.6876739989983;974.9805003900024;986.5363279258737;994.4033579852176;995.7162208770466;15202.239888005335;18533.721994169828;18616.90691546588;18670;18671.439856804318;56160.719196403756;57721.845563089344;57774.7111264441;58156.83686326334;58348;82114;86666.69999950132;86769;87361;88806.11193888237;5313.628046036741;6842.349976752201;7035.859282814417;7097.751578694721;8120.390970677105;12950.287734174712;13289.933550332187;13419.329033548478;13551.72896542044;13596.11625244356;11481.885181148082;11518.308901465938;11601.651950441501;11720.003799677123;11737.589184378507;11344.432778360884;11368.772624547628;11437.256578322715;11442.656720298404;11733.061355091742;11620.535178592761;11776.88223117792;11899.940500297444;12024;12113.576024839089;8203.138670439757;8318.542480163407;8353.874691879506;8377.790555236168;8505.277051450448;11093.33439993603;11463.968242858184;11491.367974761133;11685.357305348287;11689 17:09:12 INFO - PROCESS | 1656 | |3;mootools.html;1514.5910604136884;1557.9639539706095;1728.3950617283951;1874.2502998800487;1885.5003424092617;8549;8841.469511829295;8991.280697544196;9023.774405639862;9033.774155646111;7945.801354966129;8122.796930076751;8486.193811587904;8733.563321833915;8798.340124490673;2316.096722278309;2352.9176478823247;2353.1293421434007;2353.235198560466;2357.116081469449;2350.8001819845335;2353.305774796439;2397.352714767012;2399.028393500635;2401.8919148638265;1704.8295170482977;2174.510735084609;2277.0664027748626;2344.601417758985;2349.4713689419914;2316.8262380321544;2328.196772113618;2345.097137602027;2351.3181177458687;2351.6002279612508;2134.1783413385956;2319.605667036608;2355.3758254062686;2367.4199821043953;2373.121944880388;8076.676932922676;8939.553022348857;8947.731568052968;8959.776005599848;8986.460812351279;5178.689278643294;5304.549113325372;5342.893142137135;5365.03429382711;5366.168243922199;3457.5159477673146;3540.9114772130647;3640.1081734974837;3641.8361173747244;3651.926961460756;1053.5364439646607;1067.786442711453;1076.2788931415892;1082.9729256768564;1097.892047269237;11749.118816088827;11753.11851611124;11755.294682319089;11757.706057348549;11762.764744705059;4515.277555591156;4563.680542362069;4745.264484004984;4757.286407038971;4781.497942716034;9923.603055877684;9925.156361709336;9948.35335703177;9977.600895964226;9978.700638980843;5327.920081198785;5337;5341.465853414627;5344.171653393731;5349.411564727877;4728.078024785203;4862.124817532807;4881.194602890483;4883.3651625288485;4919.385076865392;3794;3806.2768074066394;3827.655511004023;3882.0100874276877;3906.511686039245;1093.8359246113146;1141.2752901907184;1150.9424528773527;1154.5901205072246;1161;785.6739453127003;910.8451563234266;948.7580666929889;964.0359640359641;964.9324547281764;5973.581849270509;6030.939690603038;6081.543884208613;6126.958417069109;6189.50483961282;941.9450215758394;946.4747065378781;948.4830767231875;950.3511407707759;976.9462679552693;3626;4026.939595906064;4079.0822065035723;4090.8159132839096;4159.812808423568;10551.419671918118;10857.40284284372;10955.83566246507;10962;10983.780324393467;9922.950385248172;9997.500124993721;10466.68599942003;10590.046895779416;10969.067629251604;8052.194780521901;8356.665733370719;8371.916280837235;8372.581370931552;8377.539235341992;6086.239220097488;6151.907721384272;6209.099680546386;6254.124422580751;6258.12386265914;10119.494025298853;10190.949045254727;10207.540660670436;10223.131033862204;10232.48837558134;9875.851862222216;10306.175505959654;10464.843027354747;10554.419506927192;10602.310849794738;7017.3684368407085;7174.748883789042;7187;7191.20896701352;7207.459440541876;8321.916780832114;8409.327253819558;8500.064992850657;8508.489490630582;8516.233538981522;7634.541927484369;7664.540127592362;7666.310032097138;7795.298423141944;7802.29779319864;7416.44376671741;7527.2849079337375;7572.318491335806;7575.204603516771;7638.885416718865;6918.757843475655;6938.548994315353;6991.230964593992;6993.055937448379;7045.401140903081;5008.223725322581;5008.924866127084;5022.648414610942;5092.6435149539175;5093.617978651542;5039.823606173766;5091.745412729423;5092.134337162617;5162.612804039637;5227.320448341691 17:09:12 INFO - PROCESS | 1656 | |4;prototype.html;298.6938388152144;299.9295165636076;310.43810702628235;314.1956591126717;314.90080624603246;5274.367075950891;5359.5444387227035;5412.242286079947;5472.2338872557875;5492.505674489295;4067.7559346439234;4214.8735537933835;4514.390557274767;5334.279872217249;5345.866353341168;3503.6321186275454;3533.8586456541707;4045.534763502191;4126.277901367263;4151.979240103795;3467.7052450541732;3509.5613048368955;3946.881593552198;3970.5110583531177;4180.2442973950865;3471.6354782747826;3509.491123787049;4189.22499337622;4199.895002624929;4218.725782824122;3788.6171547385284;4159.126583417486;4192.643625291854;4195.832166713328;4198.433211516437;3254.08885512057;3574.7140228781636;3608.6752192302556;3622.2755448910325;3641.2535430237003;3925.3523168677134;4845.152098382769;5133.794648214067;5232.901090770943;5254.553362964152;4731.195696731564;4737.834175803865;4753.881152971168;4771.665983381165;4780.713157210578;5434.402215756264;5631.8592035199035;5671.914921276184;5689.6586204827845;5723.599348045638;3360.8487618057247;3415.91629106245;3935.949639029775;3969.801509924492;4002.7198096133284;5542.86142846428;5725.370209276976;5932.3474417814;5934.287885453773;6106.603070800384;3915.960840391617;3930.2336044471053;3930.3907894275862;3946.9802650987135;3950.4198320671962;3897.590752970954;4076.6534844537955;4083.1425400666194;4097.344424892003;4129.194807012664;3722.962770372316;4659.945309480874;4688.0858232644305;4691.3432119503295;4706.176294223438;5607.691576963306;5711.972654493125;5717.628354156966;5748.597598168088;5763.740631671585;1242.571312897049;1354.4717560151548;1392.6866455047534;1413.247573009461;1417.206364435919;742.3244847189032;783.4320117914581;806.8260680709553;856.4518708026868;881.7927786970057;4409.2724700424715;4551.496676827732;4553.567411095941;4574.839880604163;4623;4533.365328853963;4671.27595222741;4853.19922212838;4880.658353915192;4952.5885122740565;3735.7198210134347;4158.854440094643;4265.402843601898;4450.443694538183;4463.955360446354;2102.7764815678966;2315.494928296621;2338.707661542307;2596.130296350706;2618.6595742553345;4873.295321096754;5002.59979201663;5024.773885175103;5093.847184584469;5134.127198376285;4210.410542524049;4476.440444944382;5008.449070602231;5044.445111037783;5124.692518448905;4431.977840110779;4805.38106731825;5061.240813877947;5100.291402380244;5193.519846843631;4160.816975014056;4190.20386126635;4907.631927605373;4995.300657907968;5083.212102124176;4150.792460376909;4322.178786030644;4517.661175411791;4614.838480653161;4984.358290106249;4481;4758.193445243722;4844.539768721966;5007.524285192901;5103.7192954387865;3016.36656302179;3451.240727040047;3461.6884480396884;3486.8082255476193;3576.7496275260482;4344.56554344563;4724.173269677862;4865.859022030437;5041.941192349647;5099.974500127476;3937.8818635440985;4377.759223242752;4530.637548996138;4542;4810.302506136661;4209.7474151551005;4226.387173859835;4638.698484598491;4671.827600165939;4779.569838714532;4075.1865419887863;4141.730787498803;4296.3985042094755;4624.514425985223;4670.462896766915;4195.727277726938;4490.765039614133;4751.192297309397;4772.188727916193;4814.350062741485;4140.482439695038;4613.930791038204;4618.815247390066;4643.605293550086;4647.651426142985 17:09:12 INFO - PROCESS | 1656 | |5;yui.html;787.7754839870638;791.7347688524344;798.612672853666;815.6248125862103;847.9660813567458;15357.923210383946;16136.354545818152;16161.595960101004;16173.514794556153;16191.190440477987;14298.928505357459;14541.345639446225;14729.705405891902;14783.260836958163;14982.02616829908;870.5255635678539;885.1060428966741;885.3094586222757;888.0542222533014;888.7333799860048;706.7173130747691;732.0117840914776;879.4679219072465;886.2644005475472;909.7498187998287;939.7791518993065;939.8120375924808;940.2243149401737;942.3215284994793;942.6983365323099;934.4206591913057;935.5883411299006;936.2603543200863;936.4662142578699;937.0863408177041;548.2653244652186;555.2254604826245;566.8214512428572;566.940471250521;683.774354463026;15413.5375938722;15554.922225388913;15555.22223888801;15564;15569.766453503207;12163.02695784335;13661.795073073912;14599.270036498132;14670.119792812466;14790;12956.546520871725;13005.544805931748;13070.607881763563;13224.86775132246;13553.661158471019;4411.867643970686;4469;4551.4765801932535;4603.838865639686;4635.188841952645;104872;110929.44535277353;112269;116139.2579111314;120090.99999999913;11352.375619341015;11480;11568.595099171489;11621.24461909973;11666.591669291534;23170.7682923172;23225.3032409028;23256.3023109307;23283.417914552272;23445.88277058638;8012.198780121941;8090.032473166304;8094.676212951534;8101.6354264058255;8109.796630330986;4402.977985110054;4437.423134992495;4461.977690111594;4519.977400112978;4607.170709272296;3201.2477067889026;3613.313470440608;3614.0964758810296;3617.909552261162;3623.202895363016;579.8258526483904;618.2827919613227;624.9750009999549;630.1021045010933;647.475544808697;560.6495940037477;598.5331441475656;627.7332133843098;639.5650957349047;642.7332656947315;8089.2315230053055;8427.536485493356;9016.774580635536;9138.686710429705;9275.814483710288;519.963602547818;593.0208302291586;637.9936200638027;640.1038546035494;640.9455196308272;4080.4287399764057;4321.546237645065;4516.16450956574;4516.209663308973;4536.591706746409;104486;109429.45285273682;155982;158545;166098;117423.41288293504;117983;123010;123398.38300808439;125701.74298257084;116015.41992289985;116732;119339.40330298293;120240;120394;8665.43674661145;8679.652813887375;8737.56312184401;8757.546618024553;8899.911000890168;57507.52402187689;57738.133927990275;58687.413125868195;59603;60513;64614.676926615066;65550.67224663847;75945.24054759381;84387.5780621093;88453.5577322135;13439.328033598476;13616.727665446833;13685.931570342085;13886.629233547097;14496.275186240435;15045.247737612857;15269.923650382121;15433.305501252695;15498.147601882005;16425.178741063137;14319.355628996513;14500.492482763053;14766.926165369105;14988.47540336083;15081.924590377417;14025.438982440588;14138.78791818103;14185.31181892736;14319.284035798377;14549.563513094625;13997.090189137674;14231.928840355731;14278.571642850731;14554.636134096732;14611.85388146105;10784.299020563638;11362.42827690729;11471.770564588829;11553.70796525857;11872.46573904159;11213.327200368005;11423.828642570203;11540.769184616092;11619.709507262387;11777.705557360792 17:09:12 INFO - PROCESS | 1656 | __end_tp_report 17:09:12 INFO - PROCESS | 1656 | __start_cc_report 17:09:12 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,1260 17:09:12 INFO - PROCESS | 1656 | __end_cc_report 17:09:12 INFO - PROCESS | 1656 | __startTimestamp1467936552197__endTimestamp 17:09:12 INFO - PROCESS | 1656 | 17:09:12 INFO - PROCESS | 1656 | ------- Summary: start ------- 17:09:12 INFO - PROCESS | 1656 | Number of tests: 6 17:09:12 INFO - PROCESS | 1656 | 17:09:12 INFO - PROCESS | 1656 | [#0] dojo.html Cycles:165 Average:5723.48 Median:5905.44 stddev:634.00 (10.7%) stddev-sans-first:607.99 17:09:12 INFO - PROCESS | 1656 | Values: 3350.2 3363.7 3382.3 3383.8 3433.3 5525.0 5586.0 5621.3 5677.0 5698.0 5616.2 5697.4 5702.5 5751.1 5819.8 5137.4 5181.1 5184.5 5209.5 5224.9 5161.2 5289.0 5289.9 5319.5 5321.1 5258.1 5266.6 5282.0 5290.9 5321.0 5099.0 5141.7 5159.2 5188.2 5198.5 5184.8 5199.1 5207.0 5219.9 5235.5 5563.3 5677.9 5688.4 5767.8 5773.4 6050.6 6108.5 6129.2 6135.0 6136.1 6177.5 6274.5 6278.1 6297.4 6313.4 5775.5 5777.4 5806.6 5835.9 5841.9 6195.2 6200.1 6238.1 6272.3 6279.0 5142.4 5997.6 6068.8 6127.6 6129.3 6161.6 6253.2 6271.7 6272.7 6278.1 6191.0 6205.5 6212.0 6221.0 6239.8 6085.8 6125.8 6133.9 6140.8 6213.1 4881.1 4901.9 4906.6 4923.2 4951.0 4393.2 4408.1 4423.6 4440.2 4444.2 5596.2 5654.6 5703.1 5714.3 5775.3 6282.0 6325.4 6340.0 6340.6 6361.8 5731.4 5746.3 5755.6 5782.6 5815.2 5288.5 5390.9 5424.9 5429.8 5472.4 6225.0 6296.4 6300.2 6302.8 6327.7 6296.2 6326.1 6326.2 6342.5 6345.6 6199.1 6202.6 6232.4 6260.0 6337.9 6107.4 6182.0 6200.0 6227.4 6235.1 5144.0 6277.3 6298.8 6327.7 6356.2 5594.3 6024.8 6069.4 6082.5 6096.6 6073.6 6076.6 6078.1 6083.8 6112.3 5732.3 5905.4 5937.5 5943.9 5971.1 5987.7 6032.8 6089.8 6102.2 6115.2 5282.6 5686.9 5885.9 5940.6 5945.5 17:09:12 INFO - PROCESS | 1656 | 17:09:12 INFO - PROCESS | 1656 | [#1] ext.html Cycles:180 Average:70483.32 Median:34114.24 stddev:155639.64 (456.2%) stddev-sans-first:155987.51 17:09:12 INFO - PROCESS | 1656 | Values: 480.9 509.6 532.2 538.7 544.8 40003.8 41343.4 41539.0 41596.4 43105.8 42780.6 43017.6 43184.0 43248.1 43318.6 8603.1 8738.4 9576.0 9739.5 9748.5 11535.1 11803.0 11832.3 11978.4 11989.6 11800.1 11851.0 11868.2 11868.3 11887.0 8599.5 11578.4 11693.5 11776.1 11829.0 11629.0 11706.1 11719.5 11721.0 11726.1 40464.6 40754.4 40774.8 40800.2 40852.8 2972.3 3426.3 3431.1 3478.7 3702.4 31614.2 32024.6 32048.0 32092.0 33000.8 3237.0 3254.1 3257.5 3276.1 3311.7 499202.0 499976.0 500588.0 501103.0 501578.5 58208.4 58277.4 58472.1 58902.1 59039.7 33985.2 34110.8 34117.7 61362.0 61687.7 14223.5 16812.8 16901.6 16936.1 16955.3 11678.6 11864.0 12586.8 12780.8 13010.6 18992.6 19056.7 19123.8 19160.0 19181.3 8138.0 8165.9 8177.9 8193.7 8247.9 893.3 993.2 1065.9 1082.6 1090.0 29955.0 29962.6 30341.7 30530.8 30664.2 1021.1 1062.4 1071.9 1180.4 1189.2 15546.6 15681.0 15724.9 15907.5 16625.7 695832.0 699202.0 701163.0 701223.0 703861.0 49978.0 59706.7 59710.7 59821.0 60050.7 1246.3 1282.5 1349.4 1422.8 1448.7 30858.1 31077.2 31088.8 31876.7 31947.5 49661.5 49982.0 50228.0 50326.2 50403.7 522272.0 522307.0 524931.0 525520.0 526535.0 23081.0 26063.6 26179.6 26384.6 26424.2 46038.8 46288.8 46661.5 46800.3 47206.3 37385.0 38646.5 38934.0 39331.0 39579.0 30410.5 37627.7 38002.6 38501.2 39040.2 37252.6 37861.0 37988.1 38244.8 38337.0 31436.0 33200.8 33820.0 33900.8 34038.0 20960.2 24891.0 24974.8 25014.9 25475.4 17:09:12 INFO - PROCESS | 1656 | 17:09:12 INFO - PROCESS | 1656 | [#2] jquery.html Cycles:180 Average:36457.47 Median:1619.35 stddev:104113.75 (6429.4%) stddev-sans-first:104370.53 17:09:12 INFO - PROCESS | 1656 | Values: 359.3 374.5 384.2 386.4 405.7 15752.5 15884.1 20656.4 20696.8 21004.2 21426.6 21936.8 22203.7 22227.9 22297.6 5303.3 5986.2 6229.8 6236.8 6238.8 5329.5 6160.5 6194.4 6196.7 6204.7 5855.9 6027.4 6028.6 6073.9 6108.8 5733.6 5956.9 6032.7 6040.3 6054.8 5809.8 5871.8 5892.7 5894.6 5916.5 13516.6 16976.0 18558.4 26599.3 26656.7 2137.5 2334.6 2534.7 2611.9 2879.4 9915.4 9977.4 10127.9 10490.4 10523.4 1695.5 1777.7 1862.9 1900.4 1904.7 97163.0 120457.8 121117.4 121819.0 124176.0 9583.7 11743.5 11783.5 11816.2 11964.1 14656.8 14746.9 14770.8 14770.9 14775.9 8717.3 8748.3 8802.1 8804.4 8838.3 8780.3 8789.5 8795.0 8848.8 8922.7 4611.4 4619.2 4622.7 4628.5 4674.6 1488.5 1883.0 1897.5 1931.9 1959.5 723.6 834.5 856.7 931.5 940.2 16648.3 20977.4 21544.9 21641.4 21734.6 935.4 948.7 962.2 966.6 969.3 10918.4 11352.5 11385.7 11398.5 11440.8 611304.0 613049.0 623927.9 628992.0 629278.0 141728.3 146905.0 147334.0 147782.0 148267.0 855.7 975.0 986.5 994.4 995.7 15202.2 18533.7 18616.9 18670.0 18671.4 56160.7 57721.8 57774.7 58156.8 58348.0 82114.0 86666.7 86769.0 87361.0 88806.1 5313.6 6842.3 7035.9 7097.8 8120.4 12950.3 13289.9 13419.3 13551.7 13596.1 11481.9 11518.3 11601.7 11720.0 11737.6 11344.4 11368.8 11437.3 11442.7 11733.1 11620.5 11776.9 11899.9 12024.0 12113.6 8203.1 8318.5 8353.9 8377.8 8505.3 11093.3 11464.0 11491.4 11685.4 11689.0 17:09:12 INFO - PROCESS | 1656 | 17:09:12 INFO - PROCESS | 1656 | [#3] mootools.html Cycles:180 Average:5753.78 Median:5065.78 stddev:3230.39 (63.8%) stddev-sans-first:3223.75 17:09:12 INFO - PROCESS | 1656 | Values: 1514.6 1558.0 1728.4 1874.3 1885.5 8549.0 8841.5 8991.3 9023.8 9033.8 7945.8 8122.8 8486.2 8733.6 8798.3 2316.1 2352.9 2353.1 2353.2 2357.1 2350.8 2353.3 2397.4 2399.0 2401.9 1704.8 2174.5 2277.1 2344.6 2349.5 2316.8 2328.2 2345.1 2351.3 2351.6 2134.2 2319.6 2355.4 2367.4 2373.1 8076.7 8939.6 8947.7 8959.8 8986.5 5178.7 5304.5 5342.9 5365.0 5366.2 3457.5 3540.9 3640.1 3641.8 3651.9 1053.5 1067.8 1076.3 1083.0 1097.9 11749.1 11753.1 11755.3 11757.7 11762.8 4515.3 4563.7 4745.3 4757.3 4781.5 9923.6 9925.2 9948.4 9977.6 9978.7 5327.9 5337.0 5341.5 5344.2 5349.4 4728.1 4862.1 4881.2 4883.4 4919.4 3794.0 3806.3 3827.7 3882.0 3906.5 1093.8 1141.3 1150.9 1154.6 1161.0 785.7 910.8 948.8 964.0 964.9 5973.6 6030.9 6081.5 6127.0 6189.5 941.9 946.5 948.5 950.4 976.9 3626.0 4026.9 4079.1 4090.8 4159.8 10551.4 10857.4 10955.8 10962.0 10983.8 9923.0 9997.5 10466.7 10590.0 10969.1 8052.2 8356.7 8371.9 8372.6 8377.5 6086.2 6151.9 6209.1 6254.1 6258.1 10119.5 10190.9 10207.5 10223.1 10232.5 9875.9 10306.2 10464.8 10554.4 10602.3 7017.4 7174.7 7187.0 7191.2 7207.5 8321.9 8409.3 8500.1 8508.5 8516.2 7634.5 7664.5 7666.3 7795.3 7802.3 7416.4 7527.3 7572.3 7575.2 7638.9 6918.8 6938.5 6991.2 6993.1 7045.4 5008.2 5008.9 5022.6 5092.6 5093.6 5039.8 5091.7 5092.1 5162.6 5227.3 17:09:12 INFO - PROCESS | 1656 | 17:09:12 INFO - PROCESS | 1656 | [#4] prototype.html Cycles:180 Average:4172.49 Median:4524.15 stddev:1239.10 (27.4%) stddev-sans-first:1207.99 17:09:12 INFO - PROCESS | 1656 | Values: 298.7 299.9 310.4 314.2 314.9 5274.4 5359.5 5412.2 5472.2 5492.5 4067.8 4214.9 4514.4 5334.3 5345.9 3503.6 3533.9 4045.5 4126.3 4152.0 3467.7 3509.6 3946.9 3970.5 4180.2 3471.6 3509.5 4189.2 4199.9 4218.7 3788.6 4159.1 4192.6 4195.8 4198.4 3254.1 3574.7 3608.7 3622.3 3641.3 3925.4 4845.2 5133.8 5232.9 5254.6 4731.2 4737.8 4753.9 4771.7 4780.7 5434.4 5631.9 5671.9 5689.7 5723.6 3360.8 3415.9 3935.9 3969.8 4002.7 5542.9 5725.4 5932.3 5934.3 6106.6 3916.0 3930.2 3930.4 3947.0 3950.4 3897.6 4076.7 4083.1 4097.3 4129.2 3723.0 4659.9 4688.1 4691.3 4706.2 5607.7 5712.0 5717.6 5748.6 5763.7 1242.6 1354.5 1392.7 1413.2 1417.2 742.3 783.4 806.8 856.5 881.8 4409.3 4551.5 4553.6 4574.8 4623.0 4533.4 4671.3 4853.2 4880.7 4952.6 3735.7 4158.9 4265.4 4450.4 4464.0 2102.8 2315.5 2338.7 2596.1 2618.7 4873.3 5002.6 5024.8 5093.8 5134.1 4210.4 4476.4 5008.4 5044.4 5124.7 4432.0 4805.4 5061.2 5100.3 5193.5 4160.8 4190.2 4907.6 4995.3 5083.2 4150.8 4322.2 4517.7 4614.8 4984.4 4481.0 4758.2 4844.5 5007.5 5103.7 3016.4 3451.2 3461.7 3486.8 3576.7 4344.6 4724.2 4865.9 5041.9 5100.0 3937.9 4377.8 4530.6 4542.0 4810.3 4209.7 4226.4 4638.7 4671.8 4779.6 4075.2 4141.7 4296.4 4624.5 4670.5 4195.7 4490.8 4751.2 4772.2 4814.4 4140.5 4613.9 4618.8 4643.6 4647.7 17:09:12 INFO - PROCESS | 1656 | 17:09:12 INFO - PROCESS | 1656 | [#5] yui.html Cycles:180 Average:24431.91 Median:3407.28 stddev:38391.62 (1126.8%) stddev-sans-first:38458.27 17:09:12 INFO - PROCESS | 1656 | Values: 787.8 791.7 798.6 815.6 848.0 15357.9 16136.4 16161.6 16173.5 16191.2 14298.9 14541.3 14729.7 14783.3 14982.0 870.5 885.1 885.3 888.1 888.7 706.7 732.0 879.5 886.3 909.7 939.8 939.8 940.2 942.3 942.7 934.4 935.6 936.3 936.5 937.1 548.3 555.2 566.8 566.9 683.8 15413.5 15554.9 15555.2 15564.0 15569.8 12163.0 13661.8 14599.3 14670.1 14790.0 12956.5 13005.5 13070.6 13224.9 13553.7 4411.9 4469.0 4551.5 4603.8 4635.2 104872.0 110929.4 112269.0 116139.3 120091.0 11352.4 11480.0 11568.6 11621.2 11666.6 23170.8 23225.3 23256.3 23283.4 23445.9 8012.2 8090.0 8094.7 8101.6 8109.8 4403.0 4437.4 4462.0 4520.0 4607.2 3201.2 3613.3 3614.1 3617.9 3623.2 579.8 618.3 625.0 630.1 647.5 560.6 598.5 627.7 639.6 642.7 8089.2 8427.5 9016.8 9138.7 9275.8 520.0 593.0 638.0 640.1 640.9 4080.4 4321.5 4516.2 4516.2 4536.6 104486.0 109429.5 155982.0 158545.0 166098.0 117423.4 117983.0 123010.0 123398.4 125701.7 116015.4 116732.0 119339.4 120240.0 120394.0 8665.4 8679.7 8737.6 8757.5 8899.9 57507.5 57738.1 58687.4 59603.0 60513.0 64614.7 65550.7 75945.2 84387.6 88453.6 13439.3 13616.7 13685.9 13886.6 14496.3 15045.2 15269.9 15433.3 15498.1 16425.2 14319.4 14500.5 14766.9 14988.5 15081.9 14025.4 14138.8 14185.3 14319.3 14549.6 13997.1 14231.9 14278.6 14554.6 14611.9 10784.3 11362.4 11471.8 11553.7 11872.5 11213.3 11423.8 11540.8 11619.7 11777.7 17:09:12 INFO - PROCESS | 1656 | -------- Summary: end -------- 17:09:12 INFO - PROCESS | 1656 | 17:09:12 INFO - TEST-INFO | 1656: exit 0 17:09:12 INFO - TEST-OK | dromaeo_css | took 1115740ms 17:09:12 INFO - TEST-START | kraken 17:09:12 INFO - operating with platform_type : mac_ 17:09:12 INFO - Initialising browser for kraken test... 17:09:12 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/tmpVGySNQ/profile http://localhost:49280/getInfo.html) 17:09:15 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 17:09:15 INFO - PROCESS | 1661 | colorDepth:24 17:09:15 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 17:09:15 INFO - PROCESS | 1661 | __metrics 17:09:15 INFO - PROCESS | 1661 | 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" 17:09:15 INFO - PROCESS | 1661 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:09:15 INFO - TEST-INFO | 1661: exit 0 17:09:15 INFO - Browser initialized. 17:09:15 INFO - Running cycle 1/1 for kraken test... 17:09:15 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/tmpVGySNQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:09:28 INFO - PROCESS | 1662 | RSS: Main: 243630080 17:09:28 INFO - PROCESS | 1662 | 17:09:30 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection) 17:09:31 INFO - PROCESS | 1662 | RSS: Main: 294195200 17:09:31 INFO - PROCESS | 1662 | 17:09:34 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-dft) 17:09:34 INFO - PROCESS | 1662 | RSS: Main: 304152576 17:09:34 INFO - PROCESS | 1662 | 17:09:38 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-fft) 17:09:39 INFO - PROCESS | 1662 | RSS: Main: 387162112 17:09:39 INFO - PROCESS | 1662 | 17:09:41 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator) 17:09:41 INFO - PROCESS | 1662 | RSS: Main: 289701888 17:09:41 INFO - PROCESS | 1662 | 17:09:43 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:09:44 INFO - PROCESS | 1662 | RSS: Main: 293842944 17:09:44 INFO - PROCESS | 1662 | 17:09:47 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom) 17:09:48 INFO - PROCESS | 1662 | RSS: Main: 331259904 17:09:48 INFO - PROCESS | 1662 | 17:09:50 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate) 17:09:51 INFO - PROCESS | 1662 | RSS: Main: 333144064 17:09:51 INFO - PROCESS | 1662 | 17:09:54 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial) 17:09:54 INFO - PROCESS | 1662 | RSS: Main: 331624448 17:09:54 INFO - PROCESS | 1662 | 17:09:56 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:09:57 INFO - PROCESS | 1662 | RSS: Main: 311611392 17:09:57 INFO - PROCESS | 1662 | 17:09:58 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:09:59 INFO - PROCESS | 1662 | RSS: Main: 318181376 17:09:59 INFO - PROCESS | 1662 | 17:10:01 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:10:02 INFO - PROCESS | 1662 | RSS: Main: 321835008 17:10:02 INFO - PROCESS | 1662 | 17:10:05 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:10:06 INFO - PROCESS | 1662 | RSS: Main: 323764224 17:10:06 INFO - PROCESS | 1662 | 17:10:08 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:10:09 INFO - PROCESS | 1662 | RSS: Main: 325181440 17:10:09 INFO - PROCESS | 1662 | 17:10:11 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49280/tests/kraken/driver.html?testName=ai-astar) 17:10:11 INFO - PROCESS | 1662 | RSS: Main: 368590848 17:10:11 INFO - PROCESS | 1662 | 17:10:11 INFO - PROCESS | 1662 | __start_tp_report 17:10:11 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 17:10:11 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 17:10:11 INFO - PROCESS | 1662 | |i|pagename|runs| 17:10:11 INFO - PROCESS | 1662 | |0;ai-astar;99;113;113;113;114;112;113;113;114;111 17:10:11 INFO - PROCESS | 1662 | |1;audio-beat-detection;146;109;107;159;107;105;133;128;170;130 17:10:11 INFO - PROCESS | 1662 | |2;audio-dft;187;188;188;187;185;185;184;182;181;186 17:10:11 INFO - PROCESS | 1662 | |3;audio-fft;79;95;80;118;58;57;64;67;85;56 17:10:11 INFO - PROCESS | 1662 | |4;audio-oscillator;83;87;95;72;70;70;96;69;68;99 17:10:11 INFO - PROCESS | 1662 | |5;imaging-gaussian-blur;90;88;74;126;132;94;89;88;73;113 17:10:11 INFO - PROCESS | 1662 | |6;imaging-darkroom;96;97;96;97;96;98;96;97;95;95 17:10:11 INFO - PROCESS | 1662 | |7;imaging-desaturate;84;85;83;85;88;84;79;84;85;86 17:10:11 INFO - PROCESS | 1662 | |8;json-parse-financial;57;56;54;55;54;54;55;53;54;53 17:10:11 INFO - PROCESS | 1662 | |9;json-stringify-tinderbox;65;66;73;78;71;70;70;69;69;69 17:10:11 INFO - PROCESS | 1662 | |10;stanford-crypto-aes;83;85;79;85;85;85;84;85;86;100 17:10:11 INFO - PROCESS | 1662 | |11;stanford-crypto-ccm;165;138;136;132;135;160;143;146;148;142 17:10:11 INFO - PROCESS | 1662 | |12;stanford-crypto-pbkdf2;164;165;162;171;161;164;162;160;208;166 17:10:11 INFO - PROCESS | 1662 | |13;stanford-crypto-sha256-iterative;68;67;67;66;66;65;65;65;66;66 17:10:11 INFO - PROCESS | 1662 | __end_tp_report 17:10:11 INFO - PROCESS | 1662 | __start_cc_report 17:10:11 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,2059 17:10:11 INFO - PROCESS | 1662 | __end_cc_report 17:10:11 INFO - PROCESS | 1662 | __startTimestamp1467936611489__endTimestamp 17:10:11 INFO - PROCESS | 1662 | 17:10:11 INFO - PROCESS | 1662 | ------- Summary: start ------- 17:10:11 INFO - PROCESS | 1662 | Number of tests: 14 17:10:11 INFO - PROCESS | 1662 | 17:10:11 INFO - PROCESS | 1662 | [#0] ai-astar Cycles:10 Average:111.50 Median:113.00 stddev:4.48 (4.0%) stddev-sans-first:0.93 17:10:11 INFO - PROCESS | 1662 | Values: 99.0 113.0 113.0 113.0 114.0 112.0 113.0 113.0 114.0 111.0 17:10:11 INFO - PROCESS | 1662 | 17:10:11 INFO - PROCESS | 1662 | [#1] audio-beat-detection Cycles:10 Average:129.40 Median:131.50 stddev:23.17 (17.6%) stddev-sans-first:23.78 17:10:11 INFO - PROCESS | 1662 | Values: 146.0 109.0 107.0 159.0 107.0 105.0 133.0 128.0 170.0 130.0 17:10:11 INFO - PROCESS | 1662 | 17:10:11 INFO - PROCESS | 1662 | [#2] audio-dft Cycles:10 Average:185.30 Median:186.50 stddev:2.41 (1.3%) stddev-sans-first:2.47 17:10:11 INFO - PROCESS | 1662 | Values: 187.0 188.0 188.0 187.0 185.0 185.0 184.0 182.0 181.0 186.0 17:10:11 INFO - PROCESS | 1662 | 17:10:11 INFO - PROCESS | 1662 | [#3] audio-fft Cycles:10 Average:75.90 Median:73.00 stddev:19.84 (27.2%) stddev-sans-first:21.01 17:10:11 INFO - PROCESS | 1662 | Values: 79.0 95.0 80.0 118.0 58.0 57.0 64.0 67.0 85.0 56.0 17:10:11 INFO - PROCESS | 1662 | 17:10:11 INFO - PROCESS | 1662 | [#4] audio-oscillator Cycles:10 Average:80.90 Median:85.00 stddev:12.56 (14.8%) stddev-sans-first:13.30 17:10:11 INFO - PROCESS | 1662 | Values: 83.0 87.0 95.0 72.0 70.0 70.0 96.0 69.0 68.0 99.0 17:10:11 INFO - PROCESS | 1662 | 17:10:11 INFO - PROCESS | 1662 | [#5] imaging-gaussian-blur Cycles:10 Average:96.70 Median:88.00 stddev:20.30 (23.1%) stddev-sans-first:21.39 17:10:11 INFO - PROCESS | 1662 | Values: 90.0 88.0 74.0 126.0 132.0 94.0 89.0 88.0 73.0 113.0 17:10:11 INFO - PROCESS | 1662 | 17:10:11 INFO - PROCESS | 1662 | [#6] imaging-darkroom Cycles:10 Average:96.30 Median:96.50 stddev:0.95 (1.0%) stddev-sans-first:1.00 17:10:11 INFO - PROCESS | 1662 | Values: 96.0 97.0 96.0 97.0 96.0 98.0 96.0 97.0 95.0 95.0 17:10:11 INFO - PROCESS | 1662 | 17:10:11 INFO - PROCESS | 1662 | [#7] imaging-desaturate Cycles:10 Average:84.30 Median:85.00 stddev:2.31 (2.7%) stddev-sans-first:2.45 17:10:11 INFO - PROCESS | 1662 | Values: 84.0 85.0 83.0 85.0 88.0 84.0 79.0 84.0 85.0 86.0 17:10:11 INFO - PROCESS | 1662 | 17:10:11 INFO - PROCESS | 1662 | [#8] json-parse-financial Cycles:10 Average:54.50 Median:54.50 stddev:1.27 (2.3%) stddev-sans-first:0.97 17:10:11 INFO - PROCESS | 1662 | Values: 57.0 56.0 54.0 55.0 54.0 54.0 55.0 53.0 54.0 53.0 17:10:11 INFO - PROCESS | 1662 | 17:10:11 INFO - PROCESS | 1662 | [#9] json-stringify-tinderbox Cycles:10 Average:70.00 Median:70.00 stddev:3.62 (5.2%) stddev-sans-first:3.36 17:10:11 INFO - PROCESS | 1662 | Values: 65.0 66.0 73.0 78.0 71.0 70.0 70.0 69.0 69.0 69.0 17:10:11 INFO - PROCESS | 1662 | 17:10:11 INFO - PROCESS | 1662 | [#10] stanford-crypto-aes Cycles:10 Average:85.70 Median:85.00 stddev:5.40 (6.3%) stddev-sans-first:5.63 17:10:11 INFO - PROCESS | 1662 | Values: 83.0 85.0 79.0 85.0 85.0 85.0 84.0 85.0 86.0 100.0 17:10:11 INFO - PROCESS | 1662 | 17:10:11 INFO - PROCESS | 1662 | [#11] stanford-crypto-ccm Cycles:10 Average:144.50 Median:144.50 stddev:10.77 (7.5%) stddev-sans-first:8.50 17:10:11 INFO - PROCESS | 1662 | Values: 165.0 138.0 136.0 132.0 135.0 160.0 143.0 146.0 148.0 142.0 17:10:11 INFO - PROCESS | 1662 | 17:10:11 INFO - PROCESS | 1662 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.30 Median:164.50 stddev:14.29 (8.7%) stddev-sans-first:15.07 17:10:11 INFO - PROCESS | 1662 | Values: 164.0 165.0 162.0 171.0 161.0 164.0 162.0 160.0 208.0 166.0 17:10:11 INFO - PROCESS | 1662 | 17:10:11 INFO - PROCESS | 1662 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:66.00 stddev:0.99 (1.5%) stddev-sans-first:0.78 17:10:11 INFO - PROCESS | 1662 | Values: 68.0 67.0 67.0 66.0 66.0 65.0 65.0 65.0 66.0 66.0 17:10:11 INFO - PROCESS | 1662 | -------- Summary: end -------- 17:10:11 INFO - PROCESS | 1662 | 17:10:12 INFO - TEST-INFO | 1662: exit 0 17:10:12 INFO - TEST-OK | kraken | took 59529ms 17:10:12 INFO - SUITE-END | took 1175s 17:10:12 INFO - Completed test suite (00:19:35) 17:10:12 INFO - javascript benchmark 17:10:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3350.1959529712867, 3363.7477189210817, 3382.3404436134965, 3383.7631365804396, 3433.3476639438504, 5525.0, 5585.972070139654, 5621.297337832771, 5677.034904066309, 5697.971510142449, 5616.185653080301, 5697.401772813857, 5702.45826646469, 5751.137329400592, 5819.825405237839, 5137.357830271216, 5181.145111056671, 5184.481551844823, 5209.479052094779, 5224.869378265555, 5161.225816127573, 5289.0, 5289.867753306179, 5319.468053194669, 5321.095413779647, 5258.106121959276, 5266.631335806495, 5281.973590132044, 5290.92063619046, 5321.042212401785, 5099.0, 5141.717205553693, 5159.17453207485, 5188.169892817131, 5198.454162312941, 5184.766685499161, 5199.116150254428, 5206.97396513015, 5219.895602087937, 5235.502627250406, 5563.332400112013, 5677.858053548653, 5688.374278829326, 5767.826965191051, 5773.422657734235, 6050.565225370921, 6108.450239478424, 6129.1725617041575, 6135.049067394561, 6136.0902143038675, 6177.536684748662, 6274.529410294246, 6278.089676996809, 6297.433231009231, 6313.368663133696, 5775.537956963517, 5777.422257774231, 5806.59353845231, 5835.941640583625, 5841.85395365115, 6195.19462469876, 6200.0699895015205, 6238.1266622672865, 6272.278687950853, 6279.0, 5142.357205349332, 5997.610155339976, 6068.848278792977, 6127.571070025145, 6129.264488261437, 6161.568690191643, 6253.187085678833, 6271.749130034747, 6272.686365681789, 6278.089676996809, 6191.0, 6205.5345849061505, 6212.0, 6220.968895155495, 6239.844003899939, 6085.8478538036015, 6125.75438176821, 6133.916185115316, 6140.780336713758, 6213.130161777355, 4881.085792553854, 4901.877453063702, 4906.607471402279, 4923.163062279421, 4950.950490495121, 4393.165298593257, 4408.096340250241, 4423.646108311327, 4440.222960981815, 4444.155610434007, 5596.2165296858475, 5654.6324488908085, 5703.058995265817, 5714.342850572238, 5775.27809023872, 6282.0, 6325.367463253638, 6339.953907605193, 6340.619562826245, 6361.809145725636, 5731.426857314319, 5746.281714785652, 5755.62588431759, 5782.566307526952, 5815.185873977646, 5288.497592728685, 5390.865228369322, 5424.864378390572, 5429.782808687687, 5472.398036216013, 6225.003999360262, 6296.401841825111, 6300.180976473029, 6302.842428939313, 6327.746890124344, 6296.150019747275, 6326.114343991753, 6326.2092238470195, 6342.524310676626, 6345.555811093363, 6199.070139479114, 6202.596831205957, 6232.439080482778, 6259.96870015647, 6337.8732425352155, 6107.389261073857, 6182.0, 6199.969000154971, 6227.439530442282, 6235.095911092957, 5143.974280128576, 6277.2781130170615, 6298.842528936813, 6327.715252813727, 6356.2054743157105, 5594.328680558359, 6024.819255422344, 6069.423404776539, 6082.513398927987, 6096.603720758137, 6073.605215660968, 6076.635401875901, 6078.118672792509, 6083.756649734138, 6112.32764395925, 5732.3121225453215, 5905.43898329658, 5937.5249980000635, 5943.910841337297, 5971.104334349882, 5987.700614969322, 6032.819015429544, 6089.817305480843, 6102.206713127265, 6115.205023346936, 5282.630215884851, 5686.943130568641, 5885.941140588539, 5940.584159108821, 5945.524358051259], "value": 5683.5926273276045, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [480.8653576998441, 509.57959683261316, 532.191069574247, 538.7360193505184, 544.7793643574353, 40003.79998100009, 41343.37984930221, 41539.0, 41596.37605435917, 43105.7844710776, 42780.57219427805, 43017.56982430175, 43184.0, 43248.13503729924, 43318.56681433185, 8603.096674849145, 8738.432001919886, 9575.95212023939, 9739.513024348755, 9748.463834489101, 11535.077193824516, 11803.0, 11832.349220792852, 11978.401079945968, 11989.640310790646, 11800.114991375682, 11851.0, 11868.169228154033, 11868.28790272582, 11887.049036077136, 8599.538078526602, 11578.363190024545, 11693.473793679304, 11776.116791240649, 11829.0, 11629.0, 11706.122040846885, 11719.531218751239, 11721.0, 11726.061915046776, 40464.595354046374, 40754.38868416976, 40774.79612601948, 40800.18399632021, 40852.79573602143, 2972.2717934106067, 3426.3489936912156, 3431.0564594736397, 3478.6607156244777, 3702.3520883845217, 31614.209644759067, 32024.637413071527, 32048.0, 32092.0, 33000.834995824865, 3237.0, 3254.088855120546, 3257.5439438478634, 3276.1482014676117, 3311.6853898879567, 499202.0, 499976.0, 500588.0, 501102.99999999633, 501578.4921075371, 58208.41791582115, 58277.4172258272, 58472.12291815626, 58902.11646825301, 59039.70480147572, 33985.15037124092, 34110.829445852614, 34117.658823411446, 61362.0, 61687.691561541906, 14223.502177423741, 16812.83187168137, 16901.577460563338, 16936.068516231724, 16955.321787128625, 11678.591249306233, 11863.991560717435, 12586.81119783204, 12780.808287875689, 13010.609681709564, 18992.620147596972, 19056.71414928777, 19123.80876191248, 19160.0, 19181.32865349706, 8137.959310203411, 8165.918340816635, 8177.9182208178345, 8193.71322003727, 8247.917520824834, 893.3300024981264, 993.1508560181086, 1065.9040686338267, 1082.5723839083519, 1090.0, 29954.95157669471, 29962.55056174159, 30341.688377803486, 30530.84734576313, 30664.233394165323, 1021.116734025063, 1062.4422178356424, 1071.9464026798628, 1180.392098069495, 1189.1972918280126, 15546.611334716496, 15681.0, 15724.921375393049, 15907.522774316789, 16625.6674866502, 695832.0, 699202.0, 701163.0, 701223.0, 703861.0, 49978.0, 59706.70146649326, 59710.70144649336, 59821.0, 60050.69467705915, 1246.2958428487875, 1282.4677758730209, 1349.3927732520601, 1422.8363738170242, 1448.66680663448, 30858.074257772303, 31077.223069423446, 31088.84455577798, 31876.681233188297, 31947.520787187746, 49661.50338496569, 49982.0, 50228.0, 50326.245106322705, 50403.73990650263, 522272.0, 522307.0, 524931.0, 525520.0, 526535.0, 23081.0, 26063.60904586395, 26179.607305890047, 26384.60423093617, 26424.207273781816, 46038.769806150754, 46288.768556157, 46661.53338466708, 46800.297995529414, 47206.29190562213, 37385.0, 38646.48797828245, 38934.0, 39331.0, 39579.01052473711, 30410.54384184283, 37627.68303109378, 38002.619973799905, 38501.22997540089, 39040.2191956165, 37252.62747372492, 37861.0, 37988.05029874275, 38244.80877595594, 38337.04157396087, 31436.0, 33200.83399582987, 33820.0, 33900.83049584736, 34038.0, 20960.161593536086, 24891.0, 24974.750252497244, 25014.874925625983, 25475.36311592225], "value": 20328.60424676114, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.33522982482395, 374.50004244333815, 384.1912773611548, 386.37600275554985, 405.6997821612008, 15752.527424177279, 15884.126373049477, 20656.380308590728, 20696.793032069676, 21004.159833606675, 21426.57146857062, 21936.780632193673, 22203.66694499584, 22227.888860555715, 22297.554048919013, 5303.337082864642, 5986.161937328767, 6229.813105606816, 6236.812895613139, 6238.812835614939, 5329.546988505962, 6160.537959653022, 6194.442500174983, 6196.659183744915, 6204.6587437690905, 5855.941440585582, 6027.397260273981, 6028.63828170309, 6073.878522429549, 6108.816735497942, 5733.627314224562, 5956.910646340309, 6032.698365081728, 6040.305364883051, 6054.819310234506, 5809.7676092956235, 5871.823845284648, 5892.734826932799, 5894.557908156906, 5916.526677865803, 13516.594502164951, 16976.0, 18558.44324670262, 26599.335016624547, 26656.733432665813, 2137.4976880433087, 2334.603117470017, 2534.733852945433, 2611.9216423507323, 2879.3665393613373, 9915.355501892354, 9977.401355918595, 10127.949360253226, 10490.370577765358, 10523.368597884151, 1695.5337282247358, 1777.7244527098321, 1862.850971922243, 1900.429871038683, 1904.6952487602057, 97163.0, 120457.77253364825, 121117.39441302825, 121819.0, 124176.0, 9583.712488625353, 11743.530258789553, 11783.469743861548, 11816.231944923553, 11964.102692297938, 14656.780148297785, 14746.852531474762, 14770.778438323434, 14770.926145369205, 14775.852241477447, 8717.30261579072, 8748.34387420946, 8802.07578204292, 8804.383693141419, 8838.292936565187, 8780.297576193889, 8789.516576588348, 8795.0, 8848.823023539493, 8922.73231803047, 4611.400517932714, 4619.1685496610935, 4622.722636641813, 4628.490866004737, 4674.579287864109, 1488.4939120699014, 1882.9623407531772, 1897.544589298586, 1931.8647694661238, 1959.4611481842608, 723.605634928965, 834.4742812028384, 856.7429771068779, 931.5249222896372, 940.229012209995, 16648.3340666372, 20977.37067887966, 21544.89227553852, 21641.35075947724, 21734.56530869374, 935.4247138010074, 948.6821914658528, 962.2350231565922, 966.6181858165985, 969.336004836675, 10918.399488028075, 11352.48913798881, 11385.715357116138, 11398.544058237743, 11440.828387574193, 611304.0, 613049.0, 623927.8803605953, 628992.0, 629278.0, 141728.29135854254, 146905.0, 147334.0, 147782.0, 148267.0, 855.6876739989983, 974.9805003900024, 986.5363279258737, 994.4033579852176, 995.7162208770466, 15202.239888005335, 18533.721994169828, 18616.90691546588, 18670.0, 18671.439856804318, 56160.719196403756, 57721.845563089344, 57774.7111264441, 58156.83686326334, 58348.0, 82114.0, 86666.69999950132, 86769.0, 87361.0, 88806.11193888237, 5313.628046036741, 6842.349976752201, 7035.859282814417, 7097.751578694721, 8120.390970677105, 12950.287734174712, 13289.933550332187, 13419.329033548478, 13551.72896542044, 13596.11625244356, 11481.885181148082, 11518.308901465938, 11601.651950441501, 11720.003799677123, 11737.589184378507, 11344.432778360884, 11368.772624547628, 11437.256578322715, 11442.656720298404, 11733.061355091742, 11620.535178592761, 11776.88223117792, 11899.940500297444, 12024.0, 12113.576024839089, 8203.138670439757, 8318.542480163407, 8353.874691879506, 8377.790555236168, 8505.277051450448, 11093.33439993603, 11463.968242858184, 11491.367974761133, 11685.357305348287, 11689.0], "value": 9679.048620204549, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1514.5910604136884, 1557.9639539706095, 1728.3950617283951, 1874.2502998800487, 1885.5003424092617, 8549.0, 8841.469511829295, 8991.280697544196, 9023.774405639862, 9033.774155646111, 7945.801354966129, 8122.796930076751, 8486.193811587904, 8733.563321833915, 8798.340124490673, 2316.096722278309, 2352.9176478823247, 2353.1293421434007, 2353.235198560466, 2357.116081469449, 2350.8001819845335, 2353.305774796439, 2397.352714767012, 2399.028393500635, 2401.8919148638265, 1704.8295170482977, 2174.510735084609, 2277.0664027748626, 2344.601417758985, 2349.4713689419914, 2316.8262380321544, 2328.196772113618, 2345.097137602027, 2351.3181177458687, 2351.6002279612508, 2134.1783413385956, 2319.605667036608, 2355.3758254062686, 2367.4199821043953, 2373.121944880388, 8076.676932922676, 8939.553022348857, 8947.731568052968, 8959.776005599848, 8986.460812351279, 5178.689278643294, 5304.549113325372, 5342.893142137135, 5365.03429382711, 5366.168243922199, 3457.5159477673146, 3540.9114772130647, 3640.1081734974837, 3641.8361173747244, 3651.926961460756, 1053.5364439646607, 1067.786442711453, 1076.2788931415892, 1082.9729256768564, 1097.892047269237, 11749.118816088827, 11753.11851611124, 11755.294682319089, 11757.706057348549, 11762.764744705059, 4515.277555591156, 4563.680542362069, 4745.264484004984, 4757.286407038971, 4781.497942716034, 9923.603055877684, 9925.156361709336, 9948.35335703177, 9977.600895964226, 9978.700638980843, 5327.920081198785, 5337.0, 5341.465853414627, 5344.171653393731, 5349.411564727877, 4728.078024785203, 4862.124817532807, 4881.194602890483, 4883.3651625288485, 4919.385076865392, 3794.0, 3806.2768074066394, 3827.655511004023, 3882.0100874276877, 3906.511686039245, 1093.8359246113146, 1141.2752901907184, 1150.9424528773527, 1154.5901205072246, 1161.0, 785.6739453127003, 910.8451563234266, 948.7580666929889, 964.0359640359641, 964.9324547281764, 5973.581849270509, 6030.939690603038, 6081.543884208613, 6126.958417069109, 6189.50483961282, 941.9450215758394, 946.4747065378781, 948.4830767231875, 950.3511407707759, 976.9462679552693, 3626.0, 4026.939595906064, 4079.0822065035723, 4090.8159132839096, 4159.812808423568, 10551.419671918118, 10857.40284284372, 10955.83566246507, 10962.0, 10983.780324393467, 9922.950385248172, 9997.500124993721, 10466.68599942003, 10590.046895779416, 10969.067629251604, 8052.194780521901, 8356.665733370719, 8371.916280837235, 8372.581370931552, 8377.539235341992, 6086.239220097488, 6151.907721384272, 6209.099680546386, 6254.124422580751, 6258.12386265914, 10119.494025298853, 10190.949045254727, 10207.540660670436, 10223.131033862204, 10232.48837558134, 9875.851862222216, 10306.175505959654, 10464.843027354747, 10554.419506927192, 10602.310849794738, 7017.3684368407085, 7174.748883789042, 7187.0, 7191.20896701352, 7207.459440541876, 8321.916780832114, 8409.327253819558, 8500.064992850657, 8508.489490630582, 8516.233538981522, 7634.541927484369, 7664.540127592362, 7666.310032097138, 7795.298423141944, 7802.29779319864, 7416.44376671741, 7527.2849079337375, 7572.318491335806, 7575.204603516771, 7638.885416718865, 6918.757843475655, 6938.548994315353, 6991.230964593992, 6993.055937448379, 7045.401140903081, 5008.223725322581, 5008.924866127084, 5022.648414610942, 5092.6435149539175, 5093.617978651542, 5039.823606173766, 5091.745412729423, 5092.134337162617, 5162.612804039637, 5227.320448341691], "value": 4608.808479557964, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.6938388152144, 299.9295165636076, 310.43810702628235, 314.1956591126717, 314.90080624603246, 5274.367075950891, 5359.5444387227035, 5412.242286079947, 5472.2338872557875, 5492.505674489295, 4067.7559346439234, 4214.8735537933835, 4514.390557274767, 5334.279872217249, 5345.866353341168, 3503.6321186275454, 3533.8586456541707, 4045.534763502191, 4126.277901367263, 4151.979240103795, 3467.7052450541732, 3509.5613048368955, 3946.881593552198, 3970.5110583531177, 4180.2442973950865, 3471.6354782747826, 3509.491123787049, 4189.22499337622, 4199.895002624929, 4218.725782824122, 3788.6171547385284, 4159.126583417486, 4192.643625291854, 4195.832166713328, 4198.433211516437, 3254.08885512057, 3574.7140228781636, 3608.6752192302556, 3622.2755448910325, 3641.2535430237003, 3925.3523168677134, 4845.152098382769, 5133.794648214067, 5232.901090770943, 5254.553362964152, 4731.195696731564, 4737.834175803865, 4753.881152971168, 4771.665983381165, 4780.713157210578, 5434.402215756264, 5631.8592035199035, 5671.914921276184, 5689.6586204827845, 5723.599348045638, 3360.8487618057247, 3415.91629106245, 3935.949639029775, 3969.801509924492, 4002.7198096133284, 5542.86142846428, 5725.370209276976, 5932.3474417814, 5934.287885453773, 6106.603070800384, 3915.960840391617, 3930.2336044471053, 3930.3907894275862, 3946.9802650987135, 3950.4198320671962, 3897.590752970954, 4076.6534844537955, 4083.1425400666194, 4097.344424892003, 4129.194807012664, 3722.962770372316, 4659.945309480874, 4688.0858232644305, 4691.3432119503295, 4706.176294223438, 5607.691576963306, 5711.972654493125, 5717.628354156966, 5748.597598168088, 5763.740631671585, 1242.571312897049, 1354.4717560151548, 1392.6866455047534, 1413.247573009461, 1417.206364435919, 742.3244847189032, 783.4320117914581, 806.8260680709553, 856.4518708026868, 881.7927786970057, 4409.2724700424715, 4551.496676827732, 4553.567411095941, 4574.839880604163, 4623.0, 4533.365328853963, 4671.27595222741, 4853.19922212838, 4880.658353915192, 4952.5885122740565, 3735.7198210134347, 4158.854440094643, 4265.402843601898, 4450.443694538183, 4463.955360446354, 2102.7764815678966, 2315.494928296621, 2338.707661542307, 2596.130296350706, 2618.6595742553345, 4873.295321096754, 5002.59979201663, 5024.773885175103, 5093.847184584469, 5134.127198376285, 4210.410542524049, 4476.440444944382, 5008.449070602231, 5044.445111037783, 5124.692518448905, 4431.977840110779, 4805.38106731825, 5061.240813877947, 5100.291402380244, 5193.519846843631, 4160.816975014056, 4190.20386126635, 4907.631927605373, 4995.300657907968, 5083.212102124176, 4150.792460376909, 4322.178786030644, 4517.661175411791, 4614.838480653161, 4984.358290106249, 4481.0, 4758.193445243722, 4844.539768721966, 5007.524285192901, 5103.7192954387865, 3016.36656302179, 3451.240727040047, 3461.6884480396884, 3486.8082255476193, 3576.7496275260482, 4344.56554344563, 4724.173269677862, 4865.859022030437, 5041.941192349647, 5099.974500127476, 3937.8818635440985, 4377.759223242752, 4530.637548996138, 4542.0, 4810.302506136661, 4209.7474151551005, 4226.387173859835, 4638.698484598491, 4671.827600165939, 4779.569838714532, 4075.1865419887863, 4141.730787498803, 4296.3985042094755, 4624.514425985223, 4670.462896766915, 4195.727277726938, 4490.765039614133, 4751.192297309397, 4772.188727916193, 4814.350062741485, 4140.482439695038, 4613.930791038204, 4618.815247390066, 4643.605293550086, 4647.651426142985], "value": 3789.1473988731723, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [787.7754839870638, 791.7347688524344, 798.612672853666, 815.6248125862103, 847.9660813567458, 15357.923210383946, 16136.354545818152, 16161.595960101004, 16173.514794556153, 16191.190440477987, 14298.928505357459, 14541.345639446225, 14729.705405891902, 14783.260836958163, 14982.02616829908, 870.5255635678539, 885.1060428966741, 885.3094586222757, 888.0542222533014, 888.7333799860048, 706.7173130747691, 732.0117840914776, 879.4679219072465, 886.2644005475472, 909.7498187998287, 939.7791518993065, 939.8120375924808, 940.2243149401737, 942.3215284994793, 942.6983365323099, 934.4206591913057, 935.5883411299006, 936.2603543200863, 936.4662142578699, 937.0863408177041, 548.2653244652186, 555.2254604826245, 566.8214512428572, 566.940471250521, 683.774354463026, 15413.5375938722, 15554.922225388913, 15555.22223888801, 15564.0, 15569.766453503207, 12163.02695784335, 13661.795073073912, 14599.270036498132, 14670.119792812466, 14790.0, 12956.546520871725, 13005.544805931748, 13070.607881763563, 13224.86775132246, 13553.661158471019, 4411.867643970686, 4469.0, 4551.4765801932535, 4603.838865639686, 4635.188841952645, 104872.0, 110929.44535277353, 112269.0, 116139.2579111314, 120090.99999999913, 11352.375619341015, 11480.0, 11568.595099171489, 11621.24461909973, 11666.591669291534, 23170.7682923172, 23225.3032409028, 23256.3023109307, 23283.417914552272, 23445.88277058638, 8012.198780121941, 8090.032473166304, 8094.676212951534, 8101.6354264058255, 8109.796630330986, 4402.977985110054, 4437.423134992495, 4461.977690111594, 4519.977400112978, 4607.170709272296, 3201.2477067889026, 3613.313470440608, 3614.0964758810296, 3617.909552261162, 3623.202895363016, 579.8258526483904, 618.2827919613227, 624.9750009999549, 630.1021045010933, 647.475544808697, 560.6495940037477, 598.5331441475656, 627.7332133843098, 639.5650957349047, 642.7332656947315, 8089.2315230053055, 8427.536485493356, 9016.774580635536, 9138.686710429705, 9275.814483710288, 519.963602547818, 593.0208302291586, 637.9936200638027, 640.1038546035494, 640.9455196308272, 4080.4287399764057, 4321.546237645065, 4516.16450956574, 4516.209663308973, 4536.591706746409, 104486.0, 109429.45285273682, 155982.0, 158545.0, 166098.0, 117423.41288293504, 117983.0, 123010.0, 123398.38300808439, 125701.74298257084, 116015.41992289985, 116732.0, 119339.40330298293, 120240.0, 120394.0, 8665.43674661145, 8679.652813887375, 8737.56312184401, 8757.546618024553, 8899.911000890168, 57507.52402187689, 57738.133927990275, 58687.413125868195, 59603.0, 60513.0, 64614.676926615066, 65550.67224663847, 75945.24054759381, 84387.5780621093, 88453.5577322135, 13439.328033598476, 13616.727665446833, 13685.931570342085, 13886.629233547097, 14496.275186240435, 15045.247737612857, 15269.923650382121, 15433.305501252695, 15498.147601882005, 16425.178741063137, 14319.355628996513, 14500.492482763053, 14766.926165369105, 14988.47540336083, 15081.924590377417, 14025.438982440588, 14138.78791818103, 14185.31181892736, 14319.284035798377, 14549.563513094625, 13997.090189137674, 14231.928840355731, 14278.571642850731, 14554.636134096732, 14611.85388146105, 10784.299020563638, 11362.42827690729, 11471.770564588829, 11553.70796525857, 11872.46573904159, 11213.327200368005, 11423.828642570203, 11540.769184616092, 11619.709507262387, 11777.705557360792], "value": 7906.891538893155, "unit": "score"}], "name": "dromaeo_css", "value": 7324.730136296083, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 113.0, 113.0, 113.0, 114.0, 112.0, 113.0, 113.0, 114.0, 111.0], "value": 111.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 109.0, 107.0, 159.0, 107.0, 105.0, 133.0, 128.0, 170.0, 130.0], "value": 129.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 188.0, 188.0, 187.0, 185.0, 185.0, 184.0, 182.0, 181.0, 186.0], "value": 185.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 95.0, 80.0, 118.0, 58.0, 57.0, 64.0, 67.0, 85.0, 56.0], "value": 75.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 87.0, 95.0, 72.0, 70.0, 70.0, 96.0, 69.0, 68.0, 99.0], "value": 80.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 74.0, 126.0, 132.0, 94.0, 89.0, 88.0, 73.0, 113.0], "value": 96.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 96.0, 97.0, 96.0, 98.0, 96.0, 97.0, 95.0, 95.0], "value": 96.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 83.0, 85.0, 88.0, 84.0, 79.0, 84.0, 85.0, 86.0], "value": 84.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 54.0, 55.0, 54.0, 54.0, 55.0, 53.0, 54.0, 53.0], "value": 54.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 73.0, 78.0, 71.0, 70.0, 70.0, 69.0, 69.0, 69.0], "value": 70.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 79.0, 85.0, 85.0, 85.0, 84.0, 85.0, 86.0, 100.0], "value": 85.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 138.0, 136.0, 132.0, 135.0, 160.0, 143.0, 146.0, 148.0, 142.0], "value": 144.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 165.0, 162.0, 171.0, 161.0, 164.0, 162.0, 160.0, 208.0, 166.0], "value": 168.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 67.0, 66.0, 66.0, 65.0, 65.0, 65.0, 66.0, 66.0], "value": 66.1, "unit": "score"}], "name": "kraken", "value": 1449.3999999999999, "alertThreshold": 2.0}]} 17:10:12 INFO - Return code: 0 17:10:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:10:12 INFO - # TBPL SUCCESS # 17:10:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:10:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:10:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:10:12 INFO - 2016-07-07 17:10:12.781 screenresolution[1666:10583] starting screenresolution argv=screenresolution get 17:10:12 INFO - 2016-07-07 17:10:12.783 screenresolution[1666:10583] Display 0: 1600x1200x32@60 17:10:12 INFO - 2016-07-07 17:10:12.789 screenresolution[1667:10586] starting screenresolution argv=screenresolution list 17:10:12 INFO - Available Modes on Display 0 17:10:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:10:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:10:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:10:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:10:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:10:13 INFO - Intel Iris: 17:10:13 INFO - Chipset Model: Intel Iris 17:10:13 INFO - Type: GPU 17:10:13 INFO - Bus: Built-In 17:10:13 INFO - VRAM (Dynamic, Max): 1536 MB 17:10:13 INFO - Vendor: Intel (0x8086) 17:10:13 INFO - Device ID: 0x0a2e 17:10:13 INFO - Revision ID: 0x0009 17:10:13 INFO - Displays: 17:10:13 INFO - Display: 17:10:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:10:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:10:13 INFO - Display Serial Number: 1600x1200 60 17:10:13 INFO - Main Display: Yes 17:10:13 INFO - Mirror: Off 17:10:13 INFO - Online: Yes 17:10:13 INFO - Rotation: Supported 17:10:13 INFO - Return code: 0 17:10:13 INFO - Running post-action listener: _resource_record_post_action 17:10:13 INFO - ##### 17:10:13 INFO - ##### Finished run-tests step (success) 17:10:13 INFO - ##### 17:10:13 INFO - Running post-run listener: _resource_record_post_run 17:10:13 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 31140352; Write bytes: 707767808; Read time: 306; Write time: 1985 17:10:13 INFO - TinderboxPrint: CPU usage
53.8% 17:10:13 INFO - TinderboxPrint: I/O read bytes / time
31,140,352 / 306 17:10:13 INFO - TinderboxPrint: I/O write bytes / time
707,767,808 / 1,985 17:10:13 INFO - TinderboxPrint: CPU idle
2,236.4 (46.2%) 17:10:13 INFO - TinderboxPrint: CPU system
970.1 (20.0%) 17:10:13 INFO - TinderboxPrint: CPU user
1,639.1 (33.8%) 17:10:13 INFO - TinderboxPrint: Swap in / out
435,179,520 / 0 17:10:13 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 388289024; Write bytes: 381961728; Read time: 28982; Write time: 694 17:10:13 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 30218752; Write bytes: 296337408; Read time: 292; Write time: 1171 17:10:13 INFO - Running post-run listener: _upload_blobber_files 17:10:13 INFO - Blob upload gear active. 17:10:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:10:13 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 17:10:13 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:10:13 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:10:13 INFO - (blobuploader) - INFO - Open directory for files ... 17:10:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:10:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:10:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:10:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:10:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:10:15 INFO - (blobuploader) - INFO - Done attempting. 17:10:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:10:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:10:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:10:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:10:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:10:16 INFO - (blobuploader) - INFO - Done attempting. 17:10:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:10:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:10:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:10:18 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:10:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:10:18 INFO - (blobuploader) - INFO - Done attempting. 17:10:18 INFO - (blobuploader) - INFO - Iteration through files over. 17:10:18 INFO - Return code: 0 17:10:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:10:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:10:18 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d23c06f0348eb61c99a37e201fb217bc39c799cc80b7a7aaac13b80a66483daadad49a751369442668a799e6157918805827f23e7e3bc1fabfae0833f9073f0d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fa4870ac3587e5caba7f901c8e70e197fa56f619cb4c50342cb6cb51e6b060020d2d204d60756b901ae5d9e05c6e0f0892e2958175945f85b2a8eafc37e7e79b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/084953bb41a16c7be128d56726f4f7b968bc854737dbd8dc86b65e74788749ab523125159eb31688103cc7b17a21866406ae967eb21c26d9a05e1905afca45a8"} 17:10:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:10:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:10:18 INFO - Contents: 17:10:18 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d23c06f0348eb61c99a37e201fb217bc39c799cc80b7a7aaac13b80a66483daadad49a751369442668a799e6157918805827f23e7e3bc1fabfae0833f9073f0d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fa4870ac3587e5caba7f901c8e70e197fa56f619cb4c50342cb6cb51e6b060020d2d204d60756b901ae5d9e05c6e0f0892e2958175945f85b2a8eafc37e7e79b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/084953bb41a16c7be128d56726f4f7b968bc854737dbd8dc86b65e74788749ab523125159eb31688103cc7b17a21866406ae967eb21c26d9a05e1905afca45a8"} 17:10:18 INFO - Running post-run listener: copy_logs_to_upload_dir 17:10:18 INFO - Copying logs to upload dir... 17:10:18 INFO - mkdir: /builds/slave/test/build/upload/logs 17:10:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1332.413253 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 12 secs) (at 2016-07-07 17:10:18.518935) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-07 17:10:18.522399) ========= 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.lr5pYTCItg/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.FC62Ja0KYP/Listeners TMPDIR=/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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/Autoland/sha512/d23c06f0348eb61c99a37e201fb217bc39c799cc80b7a7aaac13b80a66483daadad49a751369442668a799e6157918805827f23e7e3bc1fabfae0833f9073f0d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fa4870ac3587e5caba7f901c8e70e197fa56f619cb4c50342cb6cb51e6b060020d2d204d60756b901ae5d9e05c6e0f0892e2958175945f85b2a8eafc37e7e79b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/084953bb41a16c7be128d56726f4f7b968bc854737dbd8dc86b65e74788749ab523125159eb31688103cc7b17a21866406ae967eb21c26d9a05e1905afca45a8"} build_url:https://queue.taskcluster.net/v1/task/TZTwsMr1QTiS-8VvUsMgDA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010971 build_url: 'https://queue.taskcluster.net/v1/task/TZTwsMr1QTiS-8VvUsMgDA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d23c06f0348eb61c99a37e201fb217bc39c799cc80b7a7aaac13b80a66483daadad49a751369442668a799e6157918805827f23e7e3bc1fabfae0833f9073f0d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/fa4870ac3587e5caba7f901c8e70e197fa56f619cb4c50342cb6cb51e6b060020d2d204d60756b901ae5d9e05c6e0f0892e2958175945f85b2a8eafc37e7e79b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/084953bb41a16c7be128d56726f4f7b968bc854737dbd8dc86b65e74788749ab523125159eb31688103cc7b17a21866406ae967eb21c26d9a05e1905afca45a8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-07 17:10:18.557188) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 17:10:18.557698) ========= 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.lr5pYTCItg/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.FC62Ja0KYP/Listeners TMPDIR=/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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.004972 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 17:10:18.607738) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-07 17:10:18.608379) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-07 17:10:18.609106) ========= ========= Total master_lag: 0.26 =========