builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0075 starttime: 1467800521.7 results: success (0) buildid: 20160706013523 builduid: bc102fa5c6bc4fc2bf8ed6ecd800ad68 revision: 94e6be97e007242cac69cd8331ce887345f4313c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-06 03:22:01.698126) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-06 03:22:01.698568) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-06 03:22:01.698887) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-06 03:22:01.900317) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 03:22:01.900696) ========= 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.edNhbqYFTx/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.XaKdp7AWFU/Listeners TMPDIR=/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-07-06 03:22:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 193M=0s 2016-07-06 03:22:02 (193 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.137415 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 03:22:02.099260) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 03:22:02.099569) ========= 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.edNhbqYFTx/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.XaKdp7AWFU/Listeners TMPDIR=/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.027045 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 03:22:02.182597) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-06 03:22:02.182955) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 94e6be97e007242cac69cd8331ce887345f4313c --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 94e6be97e007242cac69cd8331ce887345f4313c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.edNhbqYFTx/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.XaKdp7AWFU/Listeners TMPDIR=/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-07-06 03:22:02,269 truncating revision to first 12 chars 2016-07-06 03:22:02,270 Setting DEBUG logging. 2016-07-06 03:22:02,270 attempt 1/10 2016-07-06 03:22:02,270 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/94e6be97e007?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-06 03:22:03,747 unpacking tar archive at: autoland-94e6be97e007/testing/mozharness/ program finished with exit code 0 elapsedTime=1.729569 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-06 03:22:03.946567) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-06 03:22:03.946881) ========= script_repo_revision: 94e6be97e007242cac69cd8331ce887345f4313c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-06 03:22:03.947247) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-06 03:22:03.947519) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-06 03:22:03.966571) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-07-06 03:22:03.966857) ========= /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.edNhbqYFTx/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.XaKdp7AWFU/Listeners TMPDIR=/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 03:22:04 INFO - MultiFileLogger online at 20160706 03:22:04 in /builds/slave/test 03:22:04 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 03:22:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:22:04 INFO - {'append_to_log': False, 03:22:04 INFO - 'base_work_dir': '/builds/slave/test', 03:22:04 INFO - 'blob_upload_branch': 'Autoland', 03:22:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:22:04 INFO - 'branch': 'Autoland', 03:22:04 INFO - 'buildbot_json_path': 'buildprops.json', 03:22:04 INFO - 'config_files': ('talos/mac_config.py',), 03:22:04 INFO - 'default_actions': ('clobber', 03:22:04 INFO - 'read-buildbot-config', 03:22:04 INFO - 'download-and-extract', 03:22:04 INFO - 'populate-webroot', 03:22:04 INFO - 'create-virtualenv', 03:22:04 INFO - 'install', 03:22:04 INFO - 'run-tests'), 03:22:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:22:04 INFO - 'download_minidump_stackwalk': True, 03:22:04 INFO - 'download_symbols': 'ondemand', 03:22:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:22:04 INFO - 'tooltool.py': '/tools/tooltool.py', 03:22:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:22:04 INFO - '/tools/misc-python/virtualenv.py')}, 03:22:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:22:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:22:04 INFO - 'installer_path': 'installer.exe', 03:22:04 INFO - 'log_level': 'info', 03:22:04 INFO - 'log_name': 'talos', 03:22:04 INFO - 'log_to_console': True, 03:22:04 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:22:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:22:04 INFO - 'opt_config_files': (), 03:22:04 INFO - 'pip_index': False, 03:22:04 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:22:04 INFO - 'cmd': ('bash', 03:22:04 INFO - '-c', 03:22:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:22:04 INFO - 'enabled': True, 03:22:04 INFO - 'halt_on_failure': False, 03:22:04 INFO - 'name': 'check_screen_resolution'},), 03:22:04 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:22:04 INFO - 'cmd': ('bash', 03:22:04 INFO - '-c', 03:22:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:22:04 INFO - 'enabled': True, 03:22:04 INFO - 'halt_on_failure': False, 03:22:04 INFO - 'name': 'check_screen_resolution'},), 03:22:04 INFO - 'run_cmd_checks_enabled': True, 03:22:04 INFO - 'sps_profile': False, 03:22:04 INFO - 'sps_profile_interval': 0, 03:22:04 INFO - 'suite': 'dromaeojs', 03:22:04 INFO - 'system_bits': '32', 03:22:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:22:04 INFO - 'title': 't-yosemite-r7-0075', 03:22:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:22:04 INFO - 'use_talos_json': True, 03:22:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:22:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:22:04 INFO - 'work_dir': 'build'} 03:22:04 INFO - ##### 03:22:04 INFO - ##### Running clobber step. 03:22:04 INFO - ##### 03:22:04 INFO - Running pre-action listener: _resource_record_pre_action 03:22:04 INFO - Running main action method: clobber 03:22:04 INFO - rmtree: /builds/slave/test/build 03:22:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:22:06 INFO - Running post-action listener: _resource_record_post_action 03:22:06 INFO - ##### 03:22:06 INFO - ##### Finished clobber step (success) 03:22:06 INFO - ##### 03:22:06 INFO - ##### 03:22:06 INFO - ##### Running read-buildbot-config step. 03:22:06 INFO - ##### 03:22:06 INFO - Running pre-action listener: _resource_record_pre_action 03:22:06 INFO - Running main action method: read_buildbot_config 03:22:06 INFO - Using buildbot properties: 03:22:06 INFO - { 03:22:06 INFO - "project": "", 03:22:06 INFO - "product": "firefox", 03:22:06 INFO - "script_repo_revision": "production", 03:22:06 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 03:22:06 INFO - "repository": "", 03:22:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 03:22:06 INFO - "buildid": "20160706013523", 03:22:06 INFO - "pgo_build": "False", 03:22:06 INFO - "basedir": "/builds/slave/test", 03:22:06 INFO - "buildnumber": 20, 03:22:06 INFO - "slavename": "t-yosemite-r7-0075", 03:22:06 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 03:22:06 INFO - "platform": "yosemite_r7", 03:22:06 INFO - "scheduler": "tests-autoland-macosx64-talos", 03:22:06 INFO - "branch": "autoland", 03:22:06 INFO - "repo_path": "integration/autoland", 03:22:06 INFO - "revision": "94e6be97e007242cac69cd8331ce887345f4313c", 03:22:06 INFO - "stage_platform": "macosx64", 03:22:06 INFO - "builduid": "bc102fa5c6bc4fc2bf8ed6ecd800ad68", 03:22:06 INFO - "slavebuilddir": "test" 03:22:06 INFO - } 03:22:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/apSptfXeRreQkdsaxhjddQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 03:22:06 INFO - Running post-action listener: _resource_record_post_action 03:22:06 INFO - ##### 03:22:06 INFO - ##### Finished read-buildbot-config step (success) 03:22:06 INFO - ##### 03:22:06 INFO - ##### 03:22:06 INFO - ##### Running download-and-extract step. 03:22:06 INFO - ##### 03:22:06 INFO - Running pre-action listener: _resource_record_pre_action 03:22:06 INFO - Running main action method: download_and_extract 03:22:06 INFO - mkdir: /builds/slave/test/build/tests 03:22:06 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')]} 03:22:06 INFO - https://queue.taskcluster.net/v1/task/apSptfXeRreQkdsaxhjddQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 03:22:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/apSptfXeRreQkdsaxhjddQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 03:22:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/apSptfXeRreQkdsaxhjddQ/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 03:22:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/apSptfXeRreQkdsaxhjddQ/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 03:22:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/apSptfXeRreQkdsaxhjddQ/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 03:22:17 INFO - Downloaded 1199 bytes. 03:22:17 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 03:22:17 INFO - Using the following test package requirements: 03:22:17 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 03:22:17 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:22:17 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 03:22:17 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:22:17 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 03:22:17 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:22:17 INFO - u'jsshell-mac.zip'], 03:22:17 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:22:17 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 03:22:17 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 03:22:17 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:22:17 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 03:22:17 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:22:17 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 03:22:17 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:22:17 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 03:22:17 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:22:17 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 03:22:17 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:22:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:22:17 INFO - https://queue.taskcluster.net/v1/task/apSptfXeRreQkdsaxhjddQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:22:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/apSptfXeRreQkdsaxhjddQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 03:22:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/apSptfXeRreQkdsaxhjddQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 03:22:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/apSptfXeRreQkdsaxhjddQ/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 03:22:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/apSptfXeRreQkdsaxhjddQ/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 03:22:18 INFO - Downloaded 18804306 bytes. 03:22:18 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'] 03:22:18 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 03:22:21 INFO - Return code: 0 03:22:21 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 03:22:21 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')]} 03:22:21 INFO - https://queue.taskcluster.net/v1/task/apSptfXeRreQkdsaxhjddQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:22:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/apSptfXeRreQkdsaxhjddQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 03:22:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/apSptfXeRreQkdsaxhjddQ/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 03:22:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/apSptfXeRreQkdsaxhjddQ/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 03:22:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/apSptfXeRreQkdsaxhjddQ/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 03:22:24 INFO - Downloaded 18804306 bytes. 03:22:24 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'] 03:22:24 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 03:22:28 INFO - Return code: 0 03:22:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:22:28 INFO - https://queue.taskcluster.net/v1/task/apSptfXeRreQkdsaxhjddQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 03:22:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/apSptfXeRreQkdsaxhjddQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 03:22:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/apSptfXeRreQkdsaxhjddQ/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 03:22:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/apSptfXeRreQkdsaxhjddQ/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 03:22:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/apSptfXeRreQkdsaxhjddQ/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 03:22:29 INFO - Downloaded 14403333 bytes. 03:22:29 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'] 03:22:29 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 03:22:29 INFO - Return code: 0 03:22:29 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')]} 03:22:29 INFO - https://queue.taskcluster.net/v1/task/apSptfXeRreQkdsaxhjddQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 03:22:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/apSptfXeRreQkdsaxhjddQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 03:22:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/apSptfXeRreQkdsaxhjddQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 03:22:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/apSptfXeRreQkdsaxhjddQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 03:22:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/apSptfXeRreQkdsaxhjddQ/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 03:22:34 INFO - Downloaded 112527522 bytes. 03:22:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/apSptfXeRreQkdsaxhjddQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 03:22:34 INFO - mkdir: /builds/slave/test/properties 03:22:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:22:34 INFO - Writing to file /builds/slave/test/properties/build_url 03:22:34 INFO - Contents: 03:22:34 INFO - build_url:https://queue.taskcluster.net/v1/task/apSptfXeRreQkdsaxhjddQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 03:22:34 INFO - Running post-action listener: _resource_record_post_action 03:22:34 INFO - Running post-action listener: set_extra_try_arguments 03:22:34 INFO - ##### 03:22:34 INFO - ##### Finished download-and-extract step (success) 03:22:34 INFO - ##### 03:22:34 INFO - ##### 03:22:34 INFO - ##### Running populate-webroot step. 03:22:34 INFO - ##### 03:22:34 INFO - Running pre-action listener: _resource_record_pre_action 03:22:34 INFO - Running main action method: populate_webroot 03:22:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:22:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:22:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:22:34 INFO - '--tppagecycles', 03:22:34 INFO - '7'], 03:22:34 INFO - 'tests': ['tsvgm']}}, 03:22:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:22:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:22:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:22:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:22:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:22:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:22:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:22:34 INFO - 'talos_options': ['--disable-e10s'], 03:22:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:22:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:22:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:22:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:22:34 INFO - 'talos_options': ['--disable-e10s'], 03:22:34 INFO - 'tests': ['damp', 'tps']}, 03:22:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:22:34 INFO - 'tests': ['damp', 'tps']}, 03:22:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:22:34 INFO - 'tests': ['dromaeo_dom']}, 03:22:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:22:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:22:34 INFO - 'tests': ['basic_compositor_video']}, 03:22:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:22:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:22:34 INFO - 'tests': ['a11yr', 03:22:34 INFO - 'ts_paint', 03:22:34 INFO - 'tpaint', 03:22:34 INFO - 'sessionrestore', 03:22:34 INFO - 'sessionrestore_no_auto_restore', 03:22:34 INFO - 'tabpaint']}, 03:22:34 INFO - 'other-e10s': {'tests': ['a11yr', 03:22:34 INFO - 'ts_paint', 03:22:34 INFO - 'tpaint', 03:22:34 INFO - 'sessionrestore', 03:22:34 INFO - 'sessionrestore_no_auto_restore', 03:22:34 INFO - 'tabpaint']}, 03:22:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:22:34 INFO - 'ts_paint', 03:22:34 INFO - 'tpaint', 03:22:34 INFO - 'sessionrestore', 03:22:34 INFO - 'sessionrestore_no_auto_restore', 03:22:34 INFO - 'tabpaint']}, 03:22:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:22:34 INFO - 'ts_paint', 03:22:34 INFO - 'tpaint', 03:22:34 INFO - 'sessionrestore', 03:22:34 INFO - 'sessionrestore_no_auto_restore', 03:22:34 INFO - 'tabpaint']}, 03:22:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:22:34 INFO - 'tests': ['a11yr', 03:22:34 INFO - 'ts_paint', 03:22:34 INFO - 'tpaint', 03:22:34 INFO - 'sessionrestore', 03:22:34 INFO - 'sessionrestore_no_auto_restore', 03:22:34 INFO - 'tabpaint']}, 03:22:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:22:34 INFO - 'tests': ['a11yr', 03:22:34 INFO - 'ts_paint', 03:22:34 INFO - 'tpaint', 03:22:34 INFO - 'sessionrestore', 03:22:34 INFO - 'sessionrestore_no_auto_restore', 03:22:34 INFO - 'tabpaint']}, 03:22:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:22:34 INFO - 'tests': ['tsvgx', 03:22:34 INFO - 'tsvgr_opacity', 03:22:34 INFO - 'tart', 03:22:34 INFO - 'tscrollx', 03:22:34 INFO - 'cart']}, 03:22:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:22:34 INFO - 'tsvgr_opacity', 03:22:34 INFO - 'tart', 03:22:34 INFO - 'tscrollx', 03:22:34 INFO - 'cart']}, 03:22:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:22:34 INFO - 'talos_options': ['--disable-e10s'], 03:22:34 INFO - 'tests': ['tp5o']}, 03:22:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:22:34 INFO - 'tests': ['tp5o']}, 03:22:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:22:34 INFO - 'talos_options': ['--disable-e10s', 03:22:34 INFO - '--xperf_path', 03:22:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:22:34 INFO - 'tests': ['tp5n']}, 03:22:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:22:34 INFO - 'talos_options': ['--xperf_path', 03:22:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:22:34 INFO - 'tests': ['tp5n']}}, 03:22:34 INFO - 'talos.zip': {'path': '', 03:22:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:22:34 INFO - Running post-action listener: _resource_record_post_action 03:22:34 INFO - ##### 03:22:34 INFO - ##### Finished populate-webroot step (success) 03:22:34 INFO - ##### 03:22:34 INFO - ##### 03:22:34 INFO - ##### Running create-virtualenv step. 03:22:34 INFO - ##### 03:22:34 INFO - Running pre-action listener: _resource_record_pre_action 03:22:34 INFO - Running main action method: create_virtualenv 03:22:34 INFO - Creating virtualenv /builds/slave/test/build/venv 03:22:34 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 03:22:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:22:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:22:35 INFO - Using real prefix '/tools/python27' 03:22:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:22:35 INFO - Installing distribute.............................................................................................................................................................................................done. 03:22:38 INFO - Installing pip.................done. 03:22:38 INFO - Return code: 0 03:22:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:22:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:22:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:22:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106eaaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f3f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8852dbb910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e0ed30>, '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 0x106b73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e06b20>, '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.edNhbqYFTx/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.XaKdp7AWFU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:22:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:22:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:22:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.edNhbqYFTx/Render', 03:22:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:38 INFO - 'HOME': '/Users/cltbld', 03:22:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:38 INFO - 'LOGNAME': 'cltbld', 03:22:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:38 INFO - 'MOZ_NO_REMOTE': '1', 03:22:38 INFO - 'NO_EM_RESTART': '1', 03:22:38 INFO - 'PAGER': '/bin/cat', 03:22:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:38 INFO - 'PWD': '/builds/slave/test', 03:22:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:38 INFO - 'SHELL': '/bin/bash', 03:22:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XaKdp7AWFU/Listeners', 03:22:38 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 03:22:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:38 INFO - 'USER': 'cltbld', 03:22:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:38 INFO - 'XPC_FLAGS': '0x0', 03:22:38 INFO - 'XPC_SERVICE_NAME': '0', 03:22:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:38 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 03:22:38 INFO - Cleaning up... 03:22:38 INFO - Return code: 0 03:22:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:22:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:22:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:22:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106eaaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f3f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8852dbb910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e0ed30>, '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 0x106b73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e06b20>, '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.edNhbqYFTx/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.XaKdp7AWFU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:22:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 03:22:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 03:22:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.edNhbqYFTx/Render', 03:22:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:38 INFO - 'HOME': '/Users/cltbld', 03:22:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:38 INFO - 'LOGNAME': 'cltbld', 03:22:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:38 INFO - 'MOZ_NO_REMOTE': '1', 03:22:38 INFO - 'NO_EM_RESTART': '1', 03:22:38 INFO - 'PAGER': '/bin/cat', 03:22:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:38 INFO - 'PWD': '/builds/slave/test', 03:22:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:38 INFO - 'SHELL': '/bin/bash', 03:22:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XaKdp7AWFU/Listeners', 03:22:38 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 03:22:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:38 INFO - 'USER': 'cltbld', 03:22:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:38 INFO - 'XPC_FLAGS': '0x0', 03:22:38 INFO - 'XPC_SERVICE_NAME': '0', 03:22:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:39 INFO - Downloading/unpacking psutil>=3.1.1 03:22:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:22:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:22:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:22:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:22:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:22:41 INFO - Installing collected packages: psutil 03:22:41 INFO - Running setup.py install for psutil 03:22:41 INFO - building 'psutil._psutil_osx' extension 03:22:41 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 03:22:42 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 03:22:42 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 03:22:42 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 03:22:42 INFO - building 'psutil._psutil_posix' extension 03:22:42 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 03:22:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:22:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:22:42 INFO - ^ 03:22:42 INFO - 1 warning generated. 03:22:42 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 03:22:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:22:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:22:42 INFO - Successfully installed psutil 03:22:42 INFO - Cleaning up... 03:22:42 INFO - Return code: 0 03:22:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:22: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')]} 03:22:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22: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')]} 03:22:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106eaaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f3f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8852dbb910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e0ed30>, '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 0x106b73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e06b20>, '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.edNhbqYFTx/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.XaKdp7AWFU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:22: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:22: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 mozsystemmonitor==0.3 03:22:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.edNhbqYFTx/Render', 03:22:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:42 INFO - 'HOME': '/Users/cltbld', 03:22:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:42 INFO - 'LOGNAME': 'cltbld', 03:22:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:42 INFO - 'MOZ_NO_REMOTE': '1', 03:22:42 INFO - 'NO_EM_RESTART': '1', 03:22:42 INFO - 'PAGER': '/bin/cat', 03:22:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:42 INFO - 'PWD': '/builds/slave/test', 03:22:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:42 INFO - 'SHELL': '/bin/bash', 03:22:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XaKdp7AWFU/Listeners', 03:22:43 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 03:22:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:43 INFO - 'USER': 'cltbld', 03:22:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:43 INFO - 'XPC_FLAGS': '0x0', 03:22:43 INFO - 'XPC_SERVICE_NAME': '0', 03:22:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:22: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 03:22: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 03:22: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 03:22: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 03:22: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 03:22: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 03:22:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:22:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:22:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:22:45 INFO - Installing collected packages: mozsystemmonitor 03:22:45 INFO - Running setup.py install for mozsystemmonitor 03:22:45 INFO - Successfully installed mozsystemmonitor 03:22:45 INFO - Cleaning up... 03:22:46 INFO - Return code: 0 03:22:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:22: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')]} 03:22:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22: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')]} 03:22:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22: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', '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 0x106eaaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f3f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8852dbb910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e0ed30>, '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 0x106b73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e06b20>, '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.edNhbqYFTx/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.XaKdp7AWFU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:22: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:22: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 jsonschema==2.5.1 03:22:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.edNhbqYFTx/Render', 03:22:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:46 INFO - 'HOME': '/Users/cltbld', 03:22:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:46 INFO - 'LOGNAME': 'cltbld', 03:22:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:46 INFO - 'MOZ_NO_REMOTE': '1', 03:22:46 INFO - 'NO_EM_RESTART': '1', 03:22:46 INFO - 'PAGER': '/bin/cat', 03:22:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:46 INFO - 'PWD': '/builds/slave/test', 03:22:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:46 INFO - 'SHELL': '/bin/bash', 03:22:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XaKdp7AWFU/Listeners', 03:22:46 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 03:22:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:46 INFO - 'USER': 'cltbld', 03:22:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:46 INFO - 'XPC_FLAGS': '0x0', 03:22:46 INFO - 'XPC_SERVICE_NAME': '0', 03:22:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:46 INFO - Downloading/unpacking jsonschema==2.5.1 03:22: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 03:22: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 03:22: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 03:22: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 03:22: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 03:22: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 03:22:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:22:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:22:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:22:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:22:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:22:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:22:49 INFO - Downloading functools32-3.2.3-2.tar.gz 03:22:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:22:49 INFO - warning: no files found matching '*.txt' 03:22:49 INFO - no previously-included directories found matching 'build' 03:22:49 INFO - no previously-included directories found matching 'dist' 03:22:49 INFO - no previously-included directories found matching '.git*' 03:22:49 INFO - Installing collected packages: jsonschema, functools32 03:22:49 INFO - Running setup.py install for functools32 03:22:49 INFO - warning: no files found matching '*.txt' 03:22:49 INFO - no previously-included directories found matching 'build' 03:22:49 INFO - no previously-included directories found matching 'dist' 03:22:49 INFO - no previously-included directories found matching '.git*' 03:22:49 INFO - Successfully installed jsonschema functools32 03:22:49 INFO - Cleaning up... 03:22:49 INFO - Return code: 0 03:22:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:22:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:22:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:22:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:49 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 0x106eaaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f3f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8852dbb910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e0ed30>, '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 0x106b73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e06b20>, '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.edNhbqYFTx/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.XaKdp7AWFU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:22:49 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 03:22:49 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 03:22:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.edNhbqYFTx/Render', 03:22:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:49 INFO - 'HOME': '/Users/cltbld', 03:22:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:49 INFO - 'LOGNAME': 'cltbld', 03:22:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:49 INFO - 'MOZ_NO_REMOTE': '1', 03:22:49 INFO - 'NO_EM_RESTART': '1', 03:22:49 INFO - 'PAGER': '/bin/cat', 03:22:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:49 INFO - 'PWD': '/builds/slave/test', 03:22:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:49 INFO - 'SHELL': '/bin/bash', 03:22:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XaKdp7AWFU/Listeners', 03:22:49 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 03:22:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:49 INFO - 'USER': 'cltbld', 03:22:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:49 INFO - 'XPC_FLAGS': '0x0', 03:22:49 INFO - 'XPC_SERVICE_NAME': '0', 03:22:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:49 INFO - Downloading/unpacking blobuploader==1.2.4 03:22: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 03:22: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 03:22: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 03:22: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 03:22: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 03:22: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 03:22:52 INFO - Downloading blobuploader-1.2.4.tar.gz 03:22:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:22:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:22:52 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 03:22:52 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 03:22:52 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 03:22:52 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 03:22:52 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 03:22:52 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 03:22:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:22:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:22:52 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 03:22:52 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 03:22:52 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 03:22:52 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 03:22:52 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 03:22:52 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 03:22:53 INFO - Downloading docopt-0.6.1.tar.gz 03:22:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:22:53 INFO - Installing collected packages: blobuploader, requests, docopt 03:22:53 INFO - Running setup.py install for blobuploader 03:22:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:22:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:22:53 INFO - Running setup.py install for requests 03:22:54 INFO - Running setup.py install for docopt 03:22:54 INFO - Successfully installed blobuploader requests docopt 03:22:54 INFO - Cleaning up... 03:22:54 INFO - Return code: 0 03:22:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:22:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:22:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:22:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:54 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 0x106eaaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f3f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8852dbb910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e0ed30>, '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 0x106b73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e06b20>, '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.edNhbqYFTx/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.XaKdp7AWFU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:22:54 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 03:22:54 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 03:22:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.edNhbqYFTx/Render', 03:22:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:54 INFO - 'HOME': '/Users/cltbld', 03:22:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:54 INFO - 'LOGNAME': 'cltbld', 03:22:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:54 INFO - 'MOZ_NO_REMOTE': '1', 03:22:54 INFO - 'NO_EM_RESTART': '1', 03:22:54 INFO - 'PAGER': '/bin/cat', 03:22:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:54 INFO - 'PWD': '/builds/slave/test', 03:22:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:54 INFO - 'SHELL': '/bin/bash', 03:22:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XaKdp7AWFU/Listeners', 03:22:54 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 03:22:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:54 INFO - 'USER': 'cltbld', 03:22:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:54 INFO - 'XPC_FLAGS': '0x0', 03:22:54 INFO - 'XPC_SERVICE_NAME': '0', 03:22:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:22:54 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-jfQEe0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:22:54 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-UcxaGV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:22:54 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-1IAMfE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:22:54 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-jPX6r8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:22:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:22:54 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-Xms1Tg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:22:55 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-F6pMAz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:22:55 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-Sykd_6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:22:55 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-ZXRHoc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:22:55 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-PHtgZo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:22:55 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-jX2BAw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:22:55 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-B8RDvj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:22:55 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-9TIwtO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:22:55 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-zaUeDb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:22:55 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-GZEQMA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:22:56 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-uK8tvS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:22:56 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-TA17MR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:22:56 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-ncF4aJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:22:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:22:56 INFO - Running setup.py install for manifestparser 03:22:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:22:56 INFO - Running setup.py install for mozcrash 03:22:56 INFO - Running setup.py install for mozdebug 03:22:56 INFO - Running setup.py install for mozdevice 03:22:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:22:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:22:56 INFO - Running setup.py install for mozfile 03:22:57 INFO - Running setup.py install for mozhttpd 03:22:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:22:57 INFO - Running setup.py install for mozinfo 03:22:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:22:57 INFO - Running setup.py install for mozInstall 03:22:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:22:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:22:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:22:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:22:57 INFO - Running setup.py install for mozleak 03:22:57 INFO - Running setup.py install for mozlog 03:22:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:22:57 INFO - Running setup.py install for moznetwork 03:22:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:22:57 INFO - Running setup.py install for mozprocess 03:22:58 INFO - Running setup.py install for mozprofile 03:22:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:22:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:22:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:22:58 INFO - Running setup.py install for mozrunner 03:22:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:22:58 INFO - Running setup.py install for mozscreenshot 03:22:58 INFO - Running setup.py install for moztest 03:22:58 INFO - Running setup.py install for mozversion 03:22:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:22:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:22:58 INFO - Cleaning up... 03:22:58 INFO - Return code: 0 03:22:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:22:58 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')]} 03:22:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:22:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:58 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')]} 03:22:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:22:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:22:58 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 0x106eaaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f3f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8852dbb910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e0ed30>, '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 0x106b73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e06b20>, '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.edNhbqYFTx/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.XaKdp7AWFU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:22:58 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 03:22:58 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 03:22:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.edNhbqYFTx/Render', 03:22:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:22:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:22:58 INFO - 'HOME': '/Users/cltbld', 03:22:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:22:58 INFO - 'LOGNAME': 'cltbld', 03:22:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:22:58 INFO - 'MOZ_NO_REMOTE': '1', 03:22:58 INFO - 'NO_EM_RESTART': '1', 03:22:58 INFO - 'PAGER': '/bin/cat', 03:22:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:22:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:22:58 INFO - 'PWD': '/builds/slave/test', 03:22:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:22:58 INFO - 'SHELL': '/bin/bash', 03:22:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XaKdp7AWFU/Listeners', 03:22:58 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 03:22:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:22:58 INFO - 'USER': 'cltbld', 03:22:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:22:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:22:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:22:58 INFO - 'XPC_FLAGS': '0x0', 03:22:58 INFO - 'XPC_SERVICE_NAME': '0', 03:22:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:22:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:22:59 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-TM2Jid-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:22:59 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)) 03:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:22:59 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-Hlo4sw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:22:59 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)) 03:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:22:59 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-gDiOiT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:22:59 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)) 03:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:22:59 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-J4CWUp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:22:59 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)) 03:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:22:59 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-X2HtRA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:22:59 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)) 03:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:22:59 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-lrEgzz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:22:59 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)) 03:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:22:59 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-hs8oZH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:22:59 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)) 03:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:22:59 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-8jsYWQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:22:59 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)) 03:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:22:59 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-FNRZ4X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:23:00 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)) 03:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:23:00 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-A_PssD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:23:00 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)) 03:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:23:00 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-mEIAzG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:23:00 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)) 03:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:23:00 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-2Rx6Sg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:23:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 03:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:23:00 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-vLv8dZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:23:00 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)) 03:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:23:00 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-FQGUOy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:23:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 03:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:23:00 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-oFS5AP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:23:00 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)) 03:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:23:00 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-A_YtHt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:23:00 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)) 03:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:23:00 INFO - Running setup.py (path:/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/pip-UBoTbf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:23:00 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)) 03:23:00 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)) 03:23:00 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)) 03:23:00 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)) 03:23:00 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)) 03:23:00 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)) 03:23:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:23:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:23:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:23:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:23:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:23:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:23:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:23:03 INFO - Downloading blessings-1.6.tar.gz 03:23:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:23:03 INFO - Installing collected packages: blessings 03:23:03 INFO - Running setup.py install for blessings 03:23:03 INFO - Successfully installed blessings 03:23:03 INFO - Cleaning up... 03:23:03 INFO - Return code: 0 03:23:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:23:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:23:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:23:04 INFO - Reading from file tmpfile_stdout 03:23:04 INFO - Current package versions: 03:23:04 INFO - blessings == 1.6 03:23:04 INFO - blobuploader == 1.2.4 03:23:04 INFO - docopt == 0.6.1 03:23:04 INFO - functools32 == 3.2.3-2 03:23:04 INFO - jsonschema == 2.5.1 03:23:04 INFO - manifestparser == 1.1 03:23:04 INFO - mozInstall == 1.12 03:23:04 INFO - mozcrash == 0.17 03:23:04 INFO - mozdebug == 0.1 03:23:04 INFO - mozdevice == 0.48 03:23:04 INFO - mozfile == 1.2 03:23:04 INFO - mozhttpd == 0.7 03:23:04 INFO - mozinfo == 0.9 03:23:04 INFO - mozleak == 0.1 03:23:04 INFO - mozlog == 3.2 03:23:04 INFO - moznetwork == 0.27 03:23:04 INFO - mozprocess == 0.22 03:23:04 INFO - mozprofile == 0.28 03:23:04 INFO - mozrunner == 6.11 03:23:04 INFO - mozscreenshot == 0.1 03:23:04 INFO - mozsystemmonitor == 0.3 03:23:04 INFO - moztest == 0.7 03:23:04 INFO - mozversion == 1.4 03:23:04 INFO - psutil == 3.1.1 03:23:04 INFO - requests == 1.2.3 03:23:04 INFO - wsgiref == 0.1.2 03:23:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:23:04 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')]} 03:23:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:23:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23:04 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')]} 03:23:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:23:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23:04 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 0x106eaaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f3f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8852dbb910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e0ed30>, '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 0x106b73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e06b20>, '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.edNhbqYFTx/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.XaKdp7AWFU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:23:04 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 03:23:04 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 03:23:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.edNhbqYFTx/Render', 03:23:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:23:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:23:04 INFO - 'HOME': '/Users/cltbld', 03:23:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:23:04 INFO - 'LOGNAME': 'cltbld', 03:23:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:23:04 INFO - 'MOZ_NO_REMOTE': '1', 03:23:04 INFO - 'NO_EM_RESTART': '1', 03:23:04 INFO - 'PAGER': '/bin/cat', 03:23:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:23:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:23:04 INFO - 'PWD': '/builds/slave/test', 03:23:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:23:04 INFO - 'SHELL': '/bin/bash', 03:23:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XaKdp7AWFU/Listeners', 03:23:04 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 03:23:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:23:04 INFO - 'USER': 'cltbld', 03:23:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:23:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:23:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:23:04 INFO - 'XPC_FLAGS': '0x0', 03:23:04 INFO - 'XPC_SERVICE_NAME': '0', 03:23:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:23:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:23:04 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)) 03:23:04 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)) 03:23:04 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)) 03:23:04 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)) 03:23:04 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)) 03:23:04 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)) 03:23:04 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)) 03:23:04 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)) 03:23:04 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)) 03:23:04 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)) 03:23:04 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)) 03:23:04 INFO - Cleaning up... 03:23:04 INFO - Return code: 0 03:23:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:23:04 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')]} 03:23:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:23:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23:04 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')]} 03:23:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:23:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:23:04 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 0x106eaaa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f3f618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8852dbb910>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e0ed30>, '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 0x106b73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e06b20>, '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.edNhbqYFTx/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.XaKdp7AWFU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:23:04 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 03:23:04 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 03:23:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.edNhbqYFTx/Render', 03:23:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:23:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:23:04 INFO - 'HOME': '/Users/cltbld', 03:23:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:23:04 INFO - 'LOGNAME': 'cltbld', 03:23:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:23:04 INFO - 'MOZ_NO_REMOTE': '1', 03:23:04 INFO - 'NO_EM_RESTART': '1', 03:23:04 INFO - 'PAGER': '/bin/cat', 03:23:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:23:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:23:04 INFO - 'PWD': '/builds/slave/test', 03:23:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:23:04 INFO - 'SHELL': '/bin/bash', 03:23:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XaKdp7AWFU/Listeners', 03:23:04 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 03:23:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:23:04 INFO - 'USER': 'cltbld', 03:23:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:23:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:23:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:23:04 INFO - 'XPC_FLAGS': '0x0', 03:23:04 INFO - 'XPC_SERVICE_NAME': '0', 03:23:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:23:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:23:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:23:04 INFO - Cleaning up... 03:23:04 INFO - Return code: 0 03:23:04 INFO - Running post-action listener: _resource_record_post_action 03:23:04 INFO - Running post-action listener: _start_resource_monitoring 03:23:04 INFO - Starting resource monitoring. 03:23:04 INFO - ##### 03:23:04 INFO - ##### Finished create-virtualenv step (success) 03:23:04 INFO - ##### 03:23:04 INFO - ##### 03:23:04 INFO - ##### Running install step. 03:23:04 INFO - ##### 03:23:04 INFO - Running pre-action listener: _resource_record_pre_action 03:23:04 INFO - Running main action method: install 03:23:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:23:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:23:04 INFO - Reading from file tmpfile_stdout 03:23:04 INFO - Detecting whether we're running mozinstall >=1.0... 03:23:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:23:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:23:05 INFO - Reading from file tmpfile_stdout 03:23:05 INFO - Output received: 03:23:05 INFO - Usage: mozinstall [options] installer 03:23:05 INFO - Options: 03:23:05 INFO - -h, --help show this help message and exit 03:23:05 INFO - -d DEST, --destination=DEST 03:23:05 INFO - Directory to install application into. [default: 03:23:05 INFO - "/builds/slave/test"] 03:23:05 INFO - --app=APP Application being installed. [default: firefox] 03:23:05 INFO - mkdir: /builds/slave/test/build/application 03:23:05 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'] 03:23:05 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 03:23:39 INFO - Reading from file tmpfile_stdout 03:23:39 INFO - Output received: 03:23:39 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:23:39 INFO - Running post-action listener: _resource_record_post_action 03:23:39 INFO - ##### 03:23:39 INFO - ##### Finished install step (success) 03:23:39 INFO - ##### 03:23:39 INFO - ##### 03:23:39 INFO - ##### Running run-tests step. 03:23:39 INFO - ##### 03:23:39 INFO - Running pre-action listener: _resource_record_pre_action 03:23:39 INFO - Running main action method: run_tests 03:23:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:23:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:23:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:23:39 INFO - 2016-07-06 03:23:39.991 screenresolution[1645:8753] starting screenresolution argv=screenresolution get 03:23:39 INFO - 2016-07-06 03:23:39.993 screenresolution[1645:8753] Display 0: 1600x1200x32@60 03:23:40 INFO - 2016-07-06 03:23:39.999 screenresolution[1646:8755] starting screenresolution argv=screenresolution list 03:23:40 INFO - Available Modes on Display 0 03:23:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:23:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:23:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:23:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:23:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:23:40 INFO - Intel Iris: 03:23:40 INFO - Chipset Model: Intel Iris 03:23:40 INFO - Type: GPU 03:23:40 INFO - Bus: Built-In 03:23:40 INFO - VRAM (Dynamic, Max): 1536 MB 03:23:40 INFO - Vendor: Intel (0x8086) 03:23:40 INFO - Device ID: 0x0a2e 03:23:40 INFO - Revision ID: 0x0009 03:23:40 INFO - Displays: 03:23:40 INFO - Display: 03:23:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:23:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:23:40 INFO - Display Serial Number: 1600x1200 60 03:23:40 INFO - Main Display: Yes 03:23:40 INFO - Mirror: Off 03:23:40 INFO - Online: Yes 03:23:40 INFO - Rotation: Supported 03:23:40 INFO - Return code: 0 03:23:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:23:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:23:40 INFO - Python 2.7.3 03:23:40 INFO - Return code: 0 03:23:40 INFO - grabbing minidump binary from tooltool 03:23:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 03:23:40 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 0x106e0ed30>, '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 0x106b73db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e06b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:23:40 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 03:23:40 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 03:23:40 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 03:23:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:23:40 INFO - Return code: 0 03:23:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:23:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:23:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:23:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:23:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:23:40 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/apSptfXeRreQkdsaxhjddQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0075', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 03:23:40 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/apSptfXeRreQkdsaxhjddQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0075 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 03:23:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.edNhbqYFTx/Render', 03:23:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:23:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:23:40 INFO - 'HOME': '/Users/cltbld', 03:23:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:23:40 INFO - 'LOGNAME': 'cltbld', 03:23:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:23:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:23:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:23:40 INFO - 'MOZ_NO_REMOTE': '1', 03:23:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:23:40 INFO - 'NO_EM_RESTART': '1', 03:23:40 INFO - 'PAGER': '/bin/cat', 03:23:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:23:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:23:40 INFO - 'PWD': '/builds/slave/test', 03:23:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:23:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:23:40 INFO - 'SHELL': '/bin/bash', 03:23:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XaKdp7AWFU/Listeners', 03:23:40 INFO - 'TMPDIR': '/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/', 03:23:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:23:40 INFO - 'USER': 'cltbld', 03:23:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:23:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:23:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:23:40 INFO - 'XPC_FLAGS': '0x0', 03:23:40 INFO - 'XPC_SERVICE_NAME': '0', 03:23:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:23:40 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/apSptfXeRreQkdsaxhjddQ/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0075', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 03:23:40 INFO - mozversion application_buildid: 20160706013523 03:23:40 INFO - mozversion application_changeset: 94e6be97e007242cac69cd8331ce887345f4313c 03:23:40 INFO - mozversion application_display_name: Nightly 03:23:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:23:40 INFO - mozversion application_name: Firefox 03:23:40 INFO - mozversion application_remotingname: firefox 03:23:40 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 03:23:40 INFO - mozversion application_vendor: Mozilla 03:23:40 INFO - mozversion application_version: 50.0a1 03:23:40 INFO - mozversion platform_buildid: 20160706013523 03:23:40 INFO - mozversion platform_changeset: 94e6be97e007242cac69cd8331ce887345f4313c 03:23:40 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 03:23:40 INFO - mozversion platform_version: 50.0a1 03:23:40 INFO - using testdate: 1467800620 03:23:40 INFO - actual date: 1467800620 03:23:40 INFO - starting webserver on 'localhost:49280' 03:23:40 INFO - SUITE-START | Running 2 tests 03:23:40 INFO - TEST-START | dromaeo_css 03:23:40 INFO - operating with platform_type : mac_ 03:23:40 INFO - Initialising browser for dromaeo_css test... 03:23:40 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/tmp3ROTU6/profile http://localhost:49280/getInfo.html) 03:23:42 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 03:23:42 INFO - PROCESS | 1656 | colorDepth:24 03:23:42 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 03:23:42 INFO - PROCESS | 1656 | __metrics 03:23:43 INFO - PROCESS | 1656 | 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" 03:23:43 INFO - PROCESS | 1656 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:23:43 INFO - TEST-INFO | 1656: exit 0 03:23:43 INFO - Browser initialized. 03:23:43 INFO - Running cycle 1/1 for dromaeo_css test... 03:23:43 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/tmp3ROTU6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:23:55 INFO - PROCESS | 1659 | RSS: Main: 243785728 03:23:55 INFO - PROCESS | 1659 | 03:24:46 INFO - PROCESS | 1659 | 1467800686552 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] 03:26:57 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49280/tests/dromaeo/cssquery-ext.html) 03:26:59 INFO - PROCESS | 1659 | RSS: Main: 357036032 03:26:59 INFO - PROCESS | 1659 | 03:30:02 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-ext.html (next: http://localhost:49280/tests/dromaeo/cssquery-jquery.html) 03:30:02 INFO - PROCESS | 1659 | RSS: Main: 373547008 03:30:02 INFO - PROCESS | 1659 | 03:33:05 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49280/tests/dromaeo/cssquery-mootools.html) 03:33:06 INFO - PROCESS | 1659 | RSS: Main: 370757632 03:33:06 INFO - PROCESS | 1659 | 03:36:08 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49280/tests/dromaeo/cssquery-prototype.html) 03:36:08 INFO - PROCESS | 1659 | RSS: Main: 366845952 03:36:08 INFO - PROCESS | 1659 | 03:39:12 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49280/tests/dromaeo/cssquery-yui.html) 03:39:12 INFO - PROCESS | 1659 | RSS: Main: 331034624 03:39:12 INFO - PROCESS | 1659 | 03:42:16 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-yui.html (next: http://localhost:49280/tests/dromaeo/cssquery-dojo.html) 03:42:16 INFO - PROCESS | 1659 | RSS: Main: 380170240 03:42:16 INFO - PROCESS | 1659 | 03:42:16 INFO - PROCESS | 1659 | __start_tp_report 03:42:16 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 03:42:16 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 03:42:16 INFO - PROCESS | 1659 | |i|pagename|runs| 03:42:16 INFO - PROCESS | 1659 | |0;dojo.html;3283.720883724883;3311.12255252358;3324.6176689680688;3331.5002749587575;3387.7120444762195;5564.944350556493;5627.296587926509;5687.772489100431;5727.885442291152;5728.656280623166;5673.801416950408;5707.172459993299;5717.885642287151;5751.856203594913;5769.480746732793;5125.333706618135;5153.665011774232;5183.455737147602;5184.637075404723;5259.710715910624;5191.091558977183;5206.869828254286;5215.374155101393;5260.421353651095;5283.920741188886;4878.170710979142;4882.292067650206;5257.185136303879;5257.36911570612;5266.34170728659;5077.543021128079;5231.738413079369;5239.842804715865;5244.213367994831;5254.65844720092;5170.224466330043;5183.792648294101;5194.532492075694;5203.713795741232;5209.400918894337;5593.077142271521;5640.2667653205235;5686;5729.455701708331;5738.311402631669;6047.21386219793;6071.68038769774;6139.355367686418;6202;6223.751049957997;6202.162708034402;6241.9375806241815;6265.843353916143;6282;6306.369363063703;5794.159846822272;5807.1869938208265;5815.15680226369;5821.068629019422;5822.737976791054;6233;6240.375962403723;6241.594296370721;6301.369863013662;6301.968490157612;6101.398774955092;6104.962156433417;6108.511319094462;6117.990531562331;6119.244515559795;6193.411625895623;6239.812805615838;6245.156903818017;6253.74985000604;6256.843578910563;6024.397560244028;6078.209832721718;6103.298120716175;6112.144299798032;6113.174721412642;6148.401415631968;6184.969075154685;6206.379362063848;6219.875602487925;6226.128342032119;4630.768461576975;4877.146499362598;4881.682690625098;4881.755912204447;4933.383327084114;4330.805113769888;4371.169477799208;4398.868033958986;4399.38408622793;4400.361947517592;5554.361248456398;5577.525910297589;5599.440055994368;5635.633683810539;5688.658680479184;6155.969220153871;6245.875082498415;6252.5935814171935;6265.968670156712;6268.843278917972;5508.91736623951;5562.443755624405;5595.104783234744;5604.85987850307;5633.887322253532;5354.544863686552;5367.463253674601;5376.7042812644895;5381.730913454312;5391.32608423947;6235.563510554218;6269.0909818076125;6273.121762953281;6273.278572963985;6282.968585157045;6106.339326076981;6111.205543279345;6122.032243765785;6145.033589251397;6269.905951410641;6234.096056071935;6241.781537646342;6243;6262.029385445263;6263.812085637438;6041.848953776191;6053.485453736482;6089.056196289582;6107.480864126599;6131.84670383244;6055.424734650202;6119.319765821106;6171.352008039091;6180.072989051679;6253.656048917172;6066.059760737093;6066.878662426815;6072.193407646707;6072.817815465543;6108.17539632144;4688.578027977498;5818.505427038749;6031.475925345497;6055.091736239599;6091.177691011657;5819.447152520504;5880;5898.882022359443;5919.85200369977;5981.3420523743225;6040.7583696651645;6096.468527292138;6116.8776624468155;6123.425291022073;6139.754409823557;5763.221965034834;5804.448577385142;5917.91123133162;5933.931892259435;5945.613535120204 03:42:16 INFO - PROCESS | 1659 | |1;ext.html;475.89530303333265;534.1587000474253;537.5565158744037;546.4753836317135;552.7429745168496;30337;39887.202255954864;41116.58883411165;41983.370249446285;42248;43586;43878.561214387846;43965;44146.337804932875;44220.336694949605;8762.255208307302;9739.707808765748;9888.456134912576;9889.50552472377;9920.553575089103;8717.259032982203;11811.350375729331;12036.976856967169;12055.457504412323;12283.815742763865;12027.879721202764;12099.274043557414;12108.576199833007;12118.333491657955;12120.81818772719;8805.383623146383;9035.638574457014;12030.759384812342;12070.396480176043;12083.45624446902;8810.427322224035;8895.644174233088;11572.132090093193;11829.645110646608;11834.171607987444;41393.58606413927;41907.580924190675;41940.580594193976;41948.79025604852;41969.37045944313;3563.465480177968;3595.227026189356;3733.533308336458;3757.4176002719623;4247.362895565659;28274.010409635564;32982;33230;33488.49767253493;33525;3205.246767009751;3272.5091236314743;3292.6213485449002;3315.6850099240532;3316.4859446785786;527063;537345;537695;538276;538825.999999996;67076.32923670798;68428;68561;68565;68713.96929045969;73286.63356683182;73876;73960.63019684867;74007;74049.6297518509;16267.023978561325;16865.831341686673;16876.83123168777;16884.07137607419;16889.98660080399;11636.301821890713;11782.646520604396;11870.584529541424;12712.173708708904;12845;18511.35210267661;18666.78665886713;19359.047863822474;21838.908054597523;22033.228836990947;8702.738917832476;8738.86891696625;8765.780855478537;8776.025394286235;8835.249003834617;875.3085062800317;935.6865450074301;1042.3068659341452;1045.9477026148813;1053.1837825685154;29217.707822921922;29432.705672942997;30417;30641;31361.8431907839;999.6751055906859;1149.8102813035923;1215.1129675337054;1230.723087305349;1254.8807863252975;16758;16873.07198104116;17027.659446810994;17159.399421020204;17182.39861604838;621004;624295;625833;626967;631376;58769;60660.696696516236;60829.87095451638;60858.69570652118;60987.69506152529;1271.9173253738477;1311.1018952017898;1311.9475220991244;1435.806166167575;1498.0412535976984;26977;30916.381672366875;31920.36159276848;32264;32317.192070198435;48665.51334486751;56284.31147065594;56289.592760181324;56399;56416.717916410154;627696;646867;647774;649489;650452;22071.66892496646;23481.882590587622;26972.595411069244;27343.72656273412;27855.72144278612;45484.54515454803;46552.76723616496;49865;50548;50715.49284507108;33882.66117338795;36740.44889326716;38716.6128338713;39226;41925.79037104795;39691.80154099211;39769.40345894756;39893.60106398899;40267.3959890596;41156.38265426081;35107.29785404226;35789.28421431609;35835.64164358428;35862.64137358593;36107.81946090358;34219;35338.29323413466;35565.82217089002;35872.4619130708;35952.28095438129;24247.75752242455;24408;25875;26258.343541410854;26505.783406916515 03:42:16 INFO - PROCESS | 1659 | |2;jquery.html;326.55588399776303;364.64811456944045;391.2741863842571;394.29421335808905;412.71316435077614;16155.75766363504;16312.75530867038;21459.356219313446;21470.248541301058;21519.462013449673;21891.452713682167;22549.54900901985;22657.320280391574;22689.432764180903;22933.312000639966;5832.591718579701;5875.471207591316;6008.969955150218;6060;6064.241969753781;4263.040815816432;4492.8876778080485;5946.375630558794;6070.332263451039;6601.438877695402;6849.109615749945;6917.100776899021;6923.688434020456;6926.099607051076;6937.583744975318;6575.309592492791;6879.58722476653;6895.413889819371;6897.2757860424435;6897.827554311132;3822.10180607557;4104.835806567734;6002.399760024006;6012.669303188323;6022.96988515059;22791.316260512212;26853.73146268532;27418.5887211692;27856.582151267747;28332.00837970682;2082.4689704125353;2436.196055301746;2459.32368598635;2616.188981415767;2693;10653.680389588324;11304.773904521946;14282.78596319314;15935.362585496567;16580.336786528525;1846.3260909767837;1875.1936667233085;1878.7412433669474;1918.060150526246;1959.5101224693826;72976.90534641985;94262.52868735613;98111;114842;126786.36606817;9604.51977401127;11451.370174640308;11492.54029838797;11643.41782910851;11794.882051179378;14591.05158164716;14611.92694036523;14612.780808287884;14622;14627.561173164822;8419.200175983271;8616.741497755078;8674.219320261207;8706.564671766386;8708.08565100668;7360.558366498028;7362.705491780269;8574.099719529484;8584.570771461402;8744.720729726352;4493.797779099883;4566.88582785433;4569.246074397687;4602.4477062752685;4650.116477869194;1625.5285967069653;1627.2758622413148;1883.849292056632;1911.8566107541712;1928.4889504281377;887.973360799177;903.4127816919055;931.3573634192386;931.7763736703278;956.6125719083782;21483.570328593654;22470.66294005591;22564.66153007706;22575;22584;930.4882314726873;952.642758965388;963.3786207895868;995.1640621877658;1049.286485190078;11143.33140011602;11215.43922803873;11406.657800266004;11567.652970410732;11661.416929153509;583634;604065;608548.9572552108;618017;619022.9048854727;94706;125788;138334;143488;147019;960.9135177834029;963.6482683820356;984.7242772023844;1063.7978784030854;1084.3819023156882;20326.695099573222;20777.168913243906;20801.751894886354;20804;20903.477413064797;56879.715601421725;57283;57511;57528.71235643795;58616;68706;77830.6108469454;82274.76587851306;85299.1470085316;86057.70913436178;6778.525503214925;8103.7163699272605;8108.75673729789;8235.588220589067;8280.337572994267;11816.350100744197;13041.869581304065;13147.408366623717;13320.400581974029;13494.595162145151;11398.316101033965;11473.4263286837;11478.88521114778;11555.653330400102;11710.707232318919;11200.21598488098;11370.31778093317;11388.259763115371;11416.94291528537;11466.77066458683;10611.779645340885;11027.283226590245;11795.174337796616;11925.642230733092;12066.939665301617;7153.463490238357;7501.437392195498;7524.736634217776;8140.959295203486;8448.66205351779;8394.0766515682;10729.89270107289;11255.212135150461;11815.387690847123;11833.644990650295 03:42:16 INFO - PROCESS | 1659 | |3;mootools.html;1540.2529773060064;1576.5112815027342;1782.144570606109;1851.1947302923238;1931.0248324596078;8716.389852710312;8992;9169.862452063217;9182.173604375605;9233.584488698008;7969.521828690283;8203.712870049549;8652.264557512603;8893.288536917047;8987.505687187202;2355.611324131516;2378.940526486843;2382.5830479666074;2385.4632707640817;2386.7971222446026;2127.585120901425;2186.0381432169875;2226.0873042052763;2272.749997500274;2362.8818559072065;2097.7272954515893;2179.182806447582;2294.8852557372065;2373.193114341124;2382.7736365045293;2337.7779110984516;2353.988230058856;2358.233574088411;2360.091364824555;2364.775346342095;2334.36972017556;2378.619420892649;2381.4760752634566;2381.48798008429;2382.3686723018413;7661.272179143029;9149.176574108362;9163.404378715362;9165.908340916572;9169.862452063224;5233.816816411445;5446.2375267462585;5464.644798088111;5467.371252305956;5472.1244600864065;3011.6084908961916;3642.7814331140216;3753.9624603753887;3760.3983362662116;3767.7550959187565;1069.171392171066;1078.5308390849927;1080.4219742437836;1092.7158938675923;1098.747288123728;12070.034397248199;12071.637850864488;12082.456289466907;12087.033037357078;12108.970737487323;4062.390641403813;4713.080949214938;4896.632752543503;4901;4904.705717656881;10127.696169114792;10135.594576217016;10136.847947280796;10165.186785057178;10169.847452288222;5434.864128396822;5438.673679579238;5438.7280635968045;5442.156465747816;5464.9180262296095;4950.777215025332;4980.626453016038;4984.40187177541;4988.6757360771435;5027.3464449621315;4031.334829753058;4045.7572545647354;4083.816228269735;4141.751494910315;4162.167566486714;1293.4244261303818;1300.4473098933104;1302.9739405211842;1316.2431601828987;1322.0283091927427;636.6371168433948;781.9491733037335;822.6010384963288;882.8346582511024;1003.5484032185545;5611.7755289788765;5878.470937615635;6076.453119219292;6091.908621370594;6195.132681424604;913.3104506097853;931.03637734944;946.2430055955346;962.7978124593774;979.4564016970508;3396.9660303397145;3757.868474603376;4112.602702824092;4112.691548133902;4162.521310049383;10657.733556661145;10882.564697412174;11004.614838480615;11027.117830573536;11038.951299626522;9920.900790992142;10278.640247591298;10349.430781307101;10876.966688164612;10980.176486763525;7613.352865006425;8133.877991830127;8392.874106888401;8434.789130271669;8436.957815210884;5997.250343707036;6101.481374083252;6206.534509911684;6236.688165591611;6254.6404030238045;10141.290109692545;10160.216884432984;10308.793824123326;10403.94798026005;10422.322549034288;9752.219822414332;10063.345882517613;10163.898361016296;10169.338992965433;10558.8416173759;7740;7830.256125668053;7902.802429939297;7911.802204944923;7922.920770792219;8508;8567.700130495403;8587.82824343522;8636.136386361313;8642.78393040179;7744.380449563909;7778.377729781717;7837.686492540234;7907.9604601976625;7947.5231486111015;7448.9627551861895;7585.2035536270105;7708.306252437307;7738.380929525511;7862.449628525948;7109.786706398816;7236.927630723625;7277.781666550012;7288.12542494904;7338.70645174187;5086.567641750493;5097.057044446789;5112.590992720499;5128.256402821496;5225.895482090413;5057;5068.670536415121;5101.877586930869;5282.181261904411;5283.947160528345 03:42:16 INFO - PROCESS | 1659 | |4;prototype.html;258.61595530637;301.818908654807;314.7026060372947;317.6347200719173;319.90242975892374;5587.888242235153;5602.663840169587;5604.635698679583;5610.186522954169;5627.143042795873;5209.687418754877;5227.947720522794;5455.1271796512565;5488.506034456898;5532.861678458041;4180.916381672365;4219.789010549475;4227.682923780713;4260.31834906415;4266.552012038738;4257.467816522934;4259.339802330644;4270.210011147932;4279.636230920375;4287.5069367022725;3952.407138929155;4071;4127.484064491939;4222;4247.214265360903;3518.384282750534;4185.853495127686;4204.495460544724;4223.324268117102;4246.299360605496;3682.4660424238336;3691.667749902495;3691.8154092295276;3703.166787472797;3708.5907355205118;5124.4106927703415;5291.285676433709;5294.258803767476;5369.140937450028;5413.891722165535;3893.377059670439;4414.602685758265;4512.593866551993;4525.389072475206;4818.012307476994;4865.2945322928335;5671.334472797778;5736.483716465496;5738.311402631669;5761.214023652639;3167.6990685884803;3901.9219615607813;4010.598940105995;4019.718619696622;4053.493313335833;5279.023380674587;5925.962956482598;5940.257467816523;5952.166696662471;5988.999999999956;3889.7277190596387;3894.6494815466745;3921.960680419691;4014.6186112319283;4106.568810274878;3740.5511338638994;4152.252594532953;4172.4784401949755;4192.685548583869;4196.958030419718;4057.857974970862;4670.7664616769025;4689.976550117228;4695.483496815347;4701.412323459567;5089.869730115952;5854.272989468117;5861.472467477947;5892.292924849045;5919.644821310735;1112.8998390144925;1276.6808297925518;1344.475654494748;1348.332575375176;1376.5732622886937;780.5667854340782;788.4165717369106;795.908470525897;848.2662496940226;850.1498501498502;4021.908607524071;4220.607199624116;4383.671224658099;4479.155736107595;4556.020455602062;4753.053107073567;4941.950580494221;5012.172991456442;5042.856785865993;5058.077930386503;3779.565349984732;3942.211557688474;4221.725587836842;4280.91438171241;4500.617447516932;2295.2081531871477;2438.561059009395;2651.8674066296608;2671.3455203475273;2677.5983602459787;4940.728259945738;5257.447967963385;5267.947320526746;5269.683818970874;5304.549113325334;4630.745309008037;4633.814647414133;4828.744526423156;4830.686005409637;5126.539261154667;4529.682922195415;4996.325496058058;5117.232415137684;5149.433562308218;5203.152880727287;4304.440422745023;4647.86056418308;4696.567546898229;4806.855794326176;4923.4584195737725;4496.7301961882395;4597.333386658849;4951.77717002743;4952.950470495249;5051.595872330131;4371.9344209836245;4415.668824838086;4829;5115.7953681852305;5118.539331460038;3065.340951695396;3100.1319630503494;3476.7044801192183;3493.253373313343;3531.845574199685;5016.473270306711;5050.787810925278;5240.397354304304;5470.206820011155;5784.4960310318775;4821.54195351441;5113.51421614946;5192.143296356059;5425.348958125051;5719.799807006735;5022.271770593316;5560.499555040065;5678.716064196691;5699.458551437607;5701.861801824316;5237.607179461479;5293.867653308698;5297.390800057889;5578.107502799695;5667.3765885753355;5002.623753716955;5390.97304513475;5732.449060168352;5744.3394009687745;5763.279590051244;5578.721063946705;5653.547716182778;5669.291338582677;5700.087985922399;5705.486506214461 03:42:16 INFO - PROCESS | 1659 | |5;yui.html;683.8598087392085;748.3302444312341;769.4921351907742;790.674200666977;892.5715656484887;14910.030847994873;15674.373025078983;16179.919100404497;16210.351585936563;16524.00855948644;14865;14957.850421495781;15067.773983390258;15097.396104155821;15277.618059548517;717.0785540580355;744.4863044499306;889.2352576783977;892.1702816380763;925.1950802801539;894.6376717429418;896.7578753736487;897.9640814367417;898.0974121008377;900.0144841398658;947.0813311088233;947.5972711597578;950.0736781637917;950.909663581962;951.110711484764;759.8670232709254;929.256594724218;931.5994122527311;965.3483898368573;966.7148191283588;710.1336369629096;712.4692104382217;713.105053158283;713.1477883928708;713.1727196452142;15236.23818809055;15250.38998440061;15274.694506109927;15294;15296.617584560363;12497.062720295924;13690.93154534231;14056.648583785487;14065.296735163201;14092.436302547885;11623.07015438763;12823.220048607525;12863.614091577268;13024.413901374366;13414.798778018338;4176.937345939813;4606.193916064675;4636.721796692175;4798.616110711169;4810.254410566367;107644;109496;110952;111301.99999999919;111503.44248278788;11084.279521831055;11225.158113141384;11255.549778008788;11280.492377843017;11619.302841829518;18213.271469141087;22298.55402891933;22305.10779568799;22318.888405557867;22477.4380640482;7999.0801057877925;8002.719904803421;8114.63484143215;8148.796280092926;8216.876746848802;4397.2085024695225;4452.48796388413;4506.752128632891;4566.84016059443;4639.211334073216;3539.203679172217;3652.6164752700583;3657.9940516358215;3658.451232315174;3668.963310366862;546.9671819690832;635.5363270177551;691.2603514239815;701.2041333086881;715.6850985566334;585.6690969602246;613.6195558753512;650.7592190889306;694.905523800017;707.6992278281709;9742;9765.902340976641;9842.360246583949;9917.950410248048;9989.400635961865;567.5884983387011;581.5522048022999;646.6766616691655;650.9446697030711;659.2583343738295;4563.520830312836;4811.639127065414;4813.542713442217;4823.4297952881125;4936.3829521309835;111275;134781;139658;140639.89040164405;147469;136962;138121.3093934524;138297;139874.20251595115;140426;89362;120337;120585;121433;121574.39212804056;9231.169194772503;9234.815303694022;9345;9387.859182112137;9423.811523769624;53118;64168;65205.8492688414;65782.34217657761;67222;91551;96807.06385872103;97682;101266;102624.46063309205;11597;12078.27530348182;12152.1493495458;12317.398738163982;12866.292353920624;11873.881261187278;13464.596062118153;13687.657808554468;13959.371828267955;13981.580552583438;11422.771544568895;11621.709457263636;13151.079424440197;13800.585982420544;14228.288585570886;13198.634348241138;13286.667833304246;14140.575782726535;14175.929120354333;14947;11374.20380573385;13874.098183618033;14229.430822767388;14545.85454145445;14892.553223403314;11059.474909803255;11381.43092845338;11597.536098555964;11801.468933898166;12012.699682508008;10665.733356666145;10757.13942884583;10789.676309710721;10803.945980270362;10914.290571112851 03:42:16 INFO - PROCESS | 1659 | __end_tp_report 03:42:16 INFO - PROCESS | 1659 | __start_cc_report 03:42:16 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,1184 03:42:16 INFO - PROCESS | 1659 | __end_cc_report 03:42:16 INFO - PROCESS | 1659 | __startTimestamp1467801736515__endTimestamp 03:42:16 INFO - PROCESS | 1659 | 03:42:16 INFO - PROCESS | 1659 | ------- Summary: start ------- 03:42:16 INFO - PROCESS | 1659 | Number of tests: 6 03:42:16 INFO - PROCESS | 1659 | 03:42:16 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:5698.39 Median:5917.91 stddev:641.39 (10.8%) stddev-sans-first:614.75 03:42:16 INFO - PROCESS | 1659 | Values: 3283.7 3311.1 3324.6 3331.5 3387.7 5564.9 5627.3 5687.8 5727.9 5728.7 5673.8 5707.2 5717.9 5751.9 5769.5 5125.3 5153.7 5183.5 5184.6 5259.7 5191.1 5206.9 5215.4 5260.4 5283.9 4878.2 4882.3 5257.2 5257.4 5266.3 5077.5 5231.7 5239.8 5244.2 5254.7 5170.2 5183.8 5194.5 5203.7 5209.4 5593.1 5640.3 5686.0 5729.5 5738.3 6047.2 6071.7 6139.4 6202.0 6223.8 6202.2 6241.9 6265.8 6282.0 6306.4 5794.2 5807.2 5815.2 5821.1 5822.7 6233.0 6240.4 6241.6 6301.4 6302.0 6101.4 6105.0 6108.5 6118.0 6119.2 6193.4 6239.8 6245.2 6253.7 6256.8 6024.4 6078.2 6103.3 6112.1 6113.2 6148.4 6185.0 6206.4 6219.9 6226.1 4630.8 4877.1 4881.7 4881.8 4933.4 4330.8 4371.2 4398.9 4399.4 4400.4 5554.4 5577.5 5599.4 5635.6 5688.7 6156.0 6245.9 6252.6 6266.0 6268.8 5508.9 5562.4 5595.1 5604.9 5633.9 5354.5 5367.5 5376.7 5381.7 5391.3 6235.6 6269.1 6273.1 6273.3 6283.0 6106.3 6111.2 6122.0 6145.0 6269.9 6234.1 6241.8 6243.0 6262.0 6263.8 6041.8 6053.5 6089.1 6107.5 6131.8 6055.4 6119.3 6171.4 6180.1 6253.7 6066.1 6066.9 6072.2 6072.8 6108.2 4688.6 5818.5 6031.5 6055.1 6091.2 5819.4 5880.0 5898.9 5919.9 5981.3 6040.8 6096.5 6116.9 6123.4 6139.8 5763.2 5804.4 5917.9 5933.9 5945.6 03:42:16 INFO - PROCESS | 1659 | 03:42:16 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:74201.27 Median:36424.13 stddev:161360.13 (443.0%) stddev-sans-first:161717.84 03:42:16 INFO - PROCESS | 1659 | Values: 475.9 534.2 537.6 546.5 552.7 30337.0 39887.2 41116.6 41983.4 42248.0 43586.0 43878.6 43965.0 44146.3 44220.3 8762.3 9739.7 9888.5 9889.5 9920.6 8717.3 11811.4 12037.0 12055.5 12283.8 12027.9 12099.3 12108.6 12118.3 12120.8 8805.4 9035.6 12030.8 12070.4 12083.5 8810.4 8895.6 11572.1 11829.6 11834.2 41393.6 41907.6 41940.6 41948.8 41969.4 3563.5 3595.2 3733.5 3757.4 4247.4 28274.0 32982.0 33230.0 33488.5 33525.0 3205.2 3272.5 3292.6 3315.7 3316.5 527063.0 537345.0 537695.0 538276.0 538826.0 67076.3 68428.0 68561.0 68565.0 68714.0 73286.6 73876.0 73960.6 74007.0 74049.6 16267.0 16865.8 16876.8 16884.1 16890.0 11636.3 11782.6 11870.6 12712.2 12845.0 18511.4 18666.8 19359.0 21838.9 22033.2 8702.7 8738.9 8765.8 8776.0 8835.2 875.3 935.7 1042.3 1045.9 1053.2 29217.7 29432.7 30417.0 30641.0 31361.8 999.7 1149.8 1215.1 1230.7 1254.9 16758.0 16873.1 17027.7 17159.4 17182.4 621004.0 624295.0 625833.0 626967.0 631376.0 58769.0 60660.7 60829.9 60858.7 60987.7 1271.9 1311.1 1311.9 1435.8 1498.0 26977.0 30916.4 31920.4 32264.0 32317.2 48665.5 56284.3 56289.6 56399.0 56416.7 627696.0 646867.0 647774.0 649489.0 650452.0 22071.7 23481.9 26972.6 27343.7 27855.7 45484.5 46552.8 49865.0 50548.0 50715.5 33882.7 36740.4 38716.6 39226.0 41925.8 39691.8 39769.4 39893.6 40267.4 41156.4 35107.3 35789.3 35835.6 35862.6 36107.8 34219.0 35338.3 35565.8 35872.5 35952.3 24247.8 24408.0 25875.0 26258.3 26505.8 03:42:16 INFO - PROCESS | 1659 | 03:42:16 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:35362.72 Median:28094.30 stddev:100994.18 (359.5%) stddev-sans-first:101243.23 03:42:16 INFO - PROCESS | 1659 | Values: 326.6 364.6 391.3 394.3 412.7 16155.8 16312.8 21459.4 21470.2 21519.5 21891.5 22549.5 22657.3 22689.4 22933.3 5832.6 5875.5 6009.0 6060.0 6064.2 4263.0 4492.9 5946.4 6070.3 6601.4 6849.1 6917.1 6923.7 6926.1 6937.6 6575.3 6879.6 6895.4 6897.3 6897.8 3822.1 4104.8 6002.4 6012.7 6023.0 22791.3 26853.7 27418.6 27856.6 28332.0 2082.5 2436.2 2459.3 2616.2 2693.0 10653.7 11304.8 14282.8 15935.4 16580.3 1846.3 1875.2 1878.7 1918.1 1959.5 72976.9 94262.5 98111.0 114842.0 126786.4 9604.5 11451.4 11492.5 11643.4 11794.9 14591.1 14611.9 14612.8 14622.0 14627.6 8419.2 8616.7 8674.2 8706.6 8708.1 7360.6 7362.7 8574.1 8584.6 8744.7 4493.8 4566.9 4569.2 4602.4 4650.1 1625.5 1627.3 1883.8 1911.9 1928.5 888.0 903.4 931.4 931.8 956.6 21483.6 22470.7 22564.7 22575.0 22584.0 930.5 952.6 963.4 995.2 1049.3 11143.3 11215.4 11406.7 11567.7 11661.4 583634.0 604065.0 608549.0 618017.0 619022.9 94706.0 125788.0 138334.0 143488.0 147019.0 960.9 963.6 984.7 1063.8 1084.4 20326.7 20777.2 20801.8 20804.0 20903.5 56879.7 57283.0 57511.0 57528.7 58616.0 68706.0 77830.6 82274.8 85299.1 86057.7 6778.5 8103.7 8108.8 8235.6 8280.3 11816.4 13041.9 13147.4 13320.4 13494.6 11398.3 11473.4 11478.9 11555.7 11710.7 11200.2 11370.3 11388.3 11416.9 11466.8 10611.8 11027.3 11795.2 11925.6 12066.9 7153.5 7501.4 7524.7 8141.0 8448.7 8394.1 10729.9 11255.2 11815.4 11833.6 03:42:16 INFO - PROCESS | 1659 | 03:42:16 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:5833.18 Median:4982.51 stddev:3276.19 (65.8%) stddev-sans-first:3269.49 03:42:16 INFO - PROCESS | 1659 | Values: 1540.3 1576.5 1782.1 1851.2 1931.0 8716.4 8992.0 9169.9 9182.2 9233.6 7969.5 8203.7 8652.3 8893.3 8987.5 2355.6 2378.9 2382.6 2385.5 2386.8 2127.6 2186.0 2226.1 2272.7 2362.9 2097.7 2179.2 2294.9 2373.2 2382.8 2337.8 2354.0 2358.2 2360.1 2364.8 2334.4 2378.6 2381.5 2381.5 2382.4 7661.3 9149.2 9163.4 9165.9 9169.9 5233.8 5446.2 5464.6 5467.4 5472.1 3011.6 3642.8 3754.0 3760.4 3767.8 1069.2 1078.5 1080.4 1092.7 1098.7 12070.0 12071.6 12082.5 12087.0 12109.0 4062.4 4713.1 4896.6 4901.0 4904.7 10127.7 10135.6 10136.8 10165.2 10169.8 5434.9 5438.7 5438.7 5442.2 5464.9 4950.8 4980.6 4984.4 4988.7 5027.3 4031.3 4045.8 4083.8 4141.8 4162.2 1293.4 1300.4 1303.0 1316.2 1322.0 636.6 781.9 822.6 882.8 1003.5 5611.8 5878.5 6076.5 6091.9 6195.1 913.3 931.0 946.2 962.8 979.5 3397.0 3757.9 4112.6 4112.7 4162.5 10657.7 10882.6 11004.6 11027.1 11039.0 9920.9 10278.6 10349.4 10877.0 10980.2 7613.4 8133.9 8392.9 8434.8 8437.0 5997.3 6101.5 6206.5 6236.7 6254.6 10141.3 10160.2 10308.8 10403.9 10422.3 9752.2 10063.3 10163.9 10169.3 10558.8 7740.0 7830.3 7902.8 7911.8 7922.9 8508.0 8567.7 8587.8 8636.1 8642.8 7744.4 7778.4 7837.7 7908.0 7947.5 7449.0 7585.2 7708.3 7738.4 7862.4 7109.8 7236.9 7277.8 7288.1 7338.7 5086.6 5097.1 5112.6 5128.3 5225.9 5057.0 5068.7 5101.9 5282.2 5283.9 03:42:16 INFO - PROCESS | 1659 | 03:42:16 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:4399.05 Median:4812.43 stddev:1335.30 (27.7%) stddev-sans-first:1302.38 03:42:16 INFO - PROCESS | 1659 | Values: 258.6 301.8 314.7 317.6 319.9 5587.9 5602.7 5604.6 5610.2 5627.1 5209.7 5227.9 5455.1 5488.5 5532.9 4180.9 4219.8 4227.7 4260.3 4266.6 4257.5 4259.3 4270.2 4279.6 4287.5 3952.4 4071.0 4127.5 4222.0 4247.2 3518.4 4185.9 4204.5 4223.3 4246.3 3682.5 3691.7 3691.8 3703.2 3708.6 5124.4 5291.3 5294.3 5369.1 5413.9 3893.4 4414.6 4512.6 4525.4 4818.0 4865.3 5671.3 5736.5 5738.3 5761.2 3167.7 3901.9 4010.6 4019.7 4053.5 5279.0 5926.0 5940.3 5952.2 5989.0 3889.7 3894.6 3922.0 4014.6 4106.6 3740.6 4152.3 4172.5 4192.7 4197.0 4057.9 4670.8 4690.0 4695.5 4701.4 5089.9 5854.3 5861.5 5892.3 5919.6 1112.9 1276.7 1344.5 1348.3 1376.6 780.6 788.4 795.9 848.3 850.1 4021.9 4220.6 4383.7 4479.2 4556.0 4753.1 4942.0 5012.2 5042.9 5058.1 3779.6 3942.2 4221.7 4280.9 4500.6 2295.2 2438.6 2651.9 2671.3 2677.6 4940.7 5257.4 5267.9 5269.7 5304.5 4630.7 4633.8 4828.7 4830.7 5126.5 4529.7 4996.3 5117.2 5149.4 5203.2 4304.4 4647.9 4696.6 4806.9 4923.5 4496.7 4597.3 4951.8 4953.0 5051.6 4371.9 4415.7 4829.0 5115.8 5118.5 3065.3 3100.1 3476.7 3493.3 3531.8 5016.5 5050.8 5240.4 5470.2 5784.5 4821.5 5113.5 5192.1 5425.3 5719.8 5022.3 5560.5 5678.7 5699.5 5701.9 5237.6 5293.9 5297.4 5578.1 5667.4 5002.6 5391.0 5732.4 5744.3 5763.3 5578.7 5653.5 5669.3 5700.1 5705.5 03:42:16 INFO - PROCESS | 1659 | 03:42:16 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:25145.81 Median:13008.32 stddev:39900.73 (306.7%) stddev-sans-first:39970.38 03:42:16 INFO - PROCESS | 1659 | Values: 683.9 748.3 769.5 790.7 892.6 14910.0 15674.4 16179.9 16210.4 16524.0 14865.0 14957.9 15067.8 15097.4 15277.6 717.1 744.5 889.2 892.2 925.2 894.6 896.8 898.0 898.1 900.0 947.1 947.6 950.1 950.9 951.1 759.9 929.3 931.6 965.3 966.7 710.1 712.5 713.1 713.1 713.2 15236.2 15250.4 15274.7 15294.0 15296.6 12497.1 13690.9 14056.6 14065.3 14092.4 11623.1 12823.2 12863.6 13024.4 13414.8 4176.9 4606.2 4636.7 4798.6 4810.3 107644.0 109496.0 110952.0 111302.0 111503.4 11084.3 11225.2 11255.5 11280.5 11619.3 18213.3 22298.6 22305.1 22318.9 22477.4 7999.1 8002.7 8114.6 8148.8 8216.9 4397.2 4452.5 4506.8 4566.8 4639.2 3539.2 3652.6 3658.0 3658.5 3669.0 547.0 635.5 691.3 701.2 715.7 585.7 613.6 650.8 694.9 707.7 9742.0 9765.9 9842.4 9918.0 9989.4 567.6 581.6 646.7 650.9 659.3 4563.5 4811.6 4813.5 4823.4 4936.4 111275.0 134781.0 139658.0 140639.9 147469.0 136962.0 138121.3 138297.0 139874.2 140426.0 89362.0 120337.0 120585.0 121433.0 121574.4 9231.2 9234.8 9345.0 9387.9 9423.8 53118.0 64168.0 65205.8 65782.3 67222.0 91551.0 96807.1 97682.0 101266.0 102624.5 11597.0 12078.3 12152.1 12317.4 12866.3 11873.9 13464.6 13687.7 13959.4 13981.6 11422.8 11621.7 13151.1 13800.6 14228.3 13198.6 13286.7 14140.6 14175.9 14947.0 11374.2 13874.1 14229.4 14545.9 14892.6 11059.5 11381.4 11597.5 11801.5 12012.7 10665.7 10757.1 10789.7 10803.9 10914.3 03:42:16 INFO - PROCESS | 1659 | -------- Summary: end -------- 03:42:16 INFO - PROCESS | 1659 | 03:42:17 INFO - TEST-INFO | 1659: exit 0 03:42:17 INFO - TEST-OK | dromaeo_css | took 1116510ms 03:42:17 INFO - TEST-START | kraken 03:42:17 INFO - operating with platform_type : mac_ 03:42:17 INFO - Initialising browser for kraken test... 03:42:17 INFO - TEST-INFO | started process 1688 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/tmptxTdaq/profile http://localhost:49280/getInfo.html) 03:42:19 INFO - PROCESS | 1688 | __metrics Screen width/height:1600/1200 03:42:19 INFO - PROCESS | 1688 | colorDepth:24 03:42:19 INFO - PROCESS | 1688 | Browser inner width/height: 1024/689 03:42:19 INFO - PROCESS | 1688 | __metrics 03:42:19 INFO - PROCESS | 1688 | 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" 03:42:19 INFO - PROCESS | 1688 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:42:20 INFO - TEST-INFO | 1688: exit 0 03:42:20 INFO - Browser initialized. 03:42:20 INFO - Running cycle 1/1 for kraken test... 03:42:20 INFO - TEST-INFO | started process 1689 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/tmptxTdaq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:42:32 INFO - PROCESS | 1689 | RSS: Main: 244875264 03:42:32 INFO - PROCESS | 1689 | 03:42:34 INFO - PROCESS | 1689 | 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) 03:42:35 INFO - PROCESS | 1689 | RSS: Main: 294371328 03:42:35 INFO - PROCESS | 1689 | 03:42:38 INFO - PROCESS | 1689 | 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) 03:42:38 INFO - PROCESS | 1689 | RSS: Main: 304320512 03:42:38 INFO - PROCESS | 1689 | 03:42:42 INFO - PROCESS | 1689 | 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) 03:42:43 INFO - PROCESS | 1689 | RSS: Main: 389726208 03:42:43 INFO - PROCESS | 1689 | 03:42:45 INFO - PROCESS | 1689 | 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) 03:42:45 INFO - PROCESS | 1689 | RSS: Main: 291241984 03:42:45 INFO - PROCESS | 1689 | 03:42:47 INFO - PROCESS | 1689 | 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) 03:42:48 INFO - PROCESS | 1689 | RSS: Main: 295194624 03:42:48 INFO - PROCESS | 1689 | 03:42:51 INFO - PROCESS | 1689 | 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) 03:42:52 INFO - PROCESS | 1689 | RSS: Main: 331264000 03:42:52 INFO - PROCESS | 1689 | 03:42:54 INFO - PROCESS | 1689 | 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) 03:42:55 INFO - PROCESS | 1689 | RSS: Main: 335663104 03:42:55 INFO - PROCESS | 1689 | 03:42:58 INFO - PROCESS | 1689 | 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) 03:42:58 INFO - PROCESS | 1689 | RSS: Main: 332533760 03:42:58 INFO - PROCESS | 1689 | 03:43:00 INFO - PROCESS | 1689 | 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) 03:43:01 INFO - PROCESS | 1689 | RSS: Main: 313651200 03:43:01 INFO - PROCESS | 1689 | 03:43:02 INFO - PROCESS | 1689 | 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) 03:43:03 INFO - PROCESS | 1689 | RSS: Main: 320438272 03:43:03 INFO - PROCESS | 1689 | 03:43:05 INFO - PROCESS | 1689 | 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) 03:43:06 INFO - PROCESS | 1689 | RSS: Main: 319901696 03:43:06 INFO - PROCESS | 1689 | 03:43:09 INFO - PROCESS | 1689 | 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) 03:43:10 INFO - PROCESS | 1689 | RSS: Main: 327651328 03:43:10 INFO - PROCESS | 1689 | 03:43:12 INFO - PROCESS | 1689 | 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) 03:43:13 INFO - PROCESS | 1689 | RSS: Main: 328990720 03:43:13 INFO - PROCESS | 1689 | 03:43:15 INFO - PROCESS | 1689 | 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) 03:43:15 INFO - PROCESS | 1689 | RSS: Main: 372461568 03:43:15 INFO - PROCESS | 1689 | 03:43:15 INFO - PROCESS | 1689 | __start_tp_report 03:43:15 INFO - PROCESS | 1689 | _x_x_mozilla_page_load 03:43:15 INFO - PROCESS | 1689 | _x_x_mozilla_page_load_details 03:43:15 INFO - PROCESS | 1689 | |i|pagename|runs| 03:43:15 INFO - PROCESS | 1689 | |0;ai-astar;101;113;113;112;112;112;112;113;112;112 03:43:15 INFO - PROCESS | 1689 | |1;audio-beat-detection;141;107;106;161;107;107;127;108;140;123 03:43:15 INFO - PROCESS | 1689 | |2;audio-dft;184;182;185;185;183;185;187;194;261;186 03:43:15 INFO - PROCESS | 1689 | |3;audio-fft;79;74;58;109;58;57;61;67;95;78 03:43:15 INFO - PROCESS | 1689 | |4;audio-oscillator;82;87;78;69;84;96;121;70;67;101 03:43:15 INFO - PROCESS | 1689 | |5;imaging-gaussian-blur;90;85;90;86;89;86;89;87;89;86 03:43:15 INFO - PROCESS | 1689 | |6;imaging-darkroom;94;93;94;107;149;94;93;95;93;94 03:43:15 INFO - PROCESS | 1689 | |7;imaging-desaturate;88;86;84;86;83;85;85;85;85;86 03:43:15 INFO - PROCESS | 1689 | |8;json-parse-financial;54;55;54;54;53;54;53;56;54;53 03:43:15 INFO - PROCESS | 1689 | |9;json-stringify-tinderbox;64;73;69;77;72;70;71;71;70;72 03:43:15 INFO - PROCESS | 1689 | |10;stanford-crypto-aes;83;84;87;81;83;82;84;91;101;79 03:43:15 INFO - PROCESS | 1689 | |11;stanford-crypto-ccm;134;135;139;145;147;134;135;135;169;141 03:43:15 INFO - PROCESS | 1689 | |12;stanford-crypto-pbkdf2;160;164;165;163;162;165;163;219;203;162 03:43:15 INFO - PROCESS | 1689 | |13;stanford-crypto-sha256-iterative;66;66;66;67;66;65;75;84;83;72 03:43:15 INFO - PROCESS | 1689 | __end_tp_report 03:43:15 INFO - PROCESS | 1689 | __start_cc_report 03:43:15 INFO - PROCESS | 1689 | _x_x_mozilla_cycle_collect,2060 03:43:15 INFO - PROCESS | 1689 | __end_cc_report 03:43:15 INFO - PROCESS | 1689 | __startTimestamp1467801795631__endTimestamp 03:43:15 INFO - PROCESS | 1689 | 03:43:15 INFO - PROCESS | 1689 | ------- Summary: start ------- 03:43:15 INFO - PROCESS | 1689 | Number of tests: 14 03:43:15 INFO - PROCESS | 1689 | 03:43:15 INFO - PROCESS | 1689 | [#0] ai-astar Cycles:10 Average:111.20 Median:112.00 stddev:3.61 (3.2%) stddev-sans-first:0.50 03:43:15 INFO - PROCESS | 1689 | Values: 101.0 113.0 113.0 112.0 112.0 112.0 112.0 113.0 112.0 112.0 03:43:15 INFO - PROCESS | 1689 | 03:43:15 INFO - PROCESS | 1689 | [#1] audio-beat-detection Cycles:10 Average:122.70 Median:125.00 stddev:19.30 (15.4%) stddev-sans-first:19.31 03:43:15 INFO - PROCESS | 1689 | Values: 141.0 107.0 106.0 161.0 107.0 107.0 127.0 108.0 140.0 123.0 03:43:15 INFO - PROCESS | 1689 | 03:43:15 INFO - PROCESS | 1689 | [#2] audio-dft Cycles:10 Average:193.20 Median:185.50 stddev:24.05 (13.0%) stddev-sans-first:25.27 03:43:15 INFO - PROCESS | 1689 | Values: 184.0 182.0 185.0 185.0 183.0 185.0 187.0 194.0 261.0 186.0 03:43:15 INFO - PROCESS | 1689 | 03:43:15 INFO - PROCESS | 1689 | [#3] audio-fft Cycles:10 Average:73.60 Median:70.50 stddev:17.40 (24.7%) stddev-sans-first:18.34 03:43:15 INFO - PROCESS | 1689 | Values: 79.0 74.0 58.0 109.0 58.0 57.0 61.0 67.0 95.0 78.0 03:43:15 INFO - PROCESS | 1689 | 03:43:15 INFO - PROCESS | 1689 | [#4] audio-oscillator Cycles:10 Average:85.50 Median:80.00 stddev:16.79 (21.0%) stddev-sans-first:17.77 03:43:15 INFO - PROCESS | 1689 | Values: 82.0 87.0 78.0 69.0 84.0 96.0 121.0 70.0 67.0 101.0 03:43:15 INFO - PROCESS | 1689 | 03:43:15 INFO - PROCESS | 1689 | [#5] imaging-gaussian-blur Cycles:10 Average:87.70 Median:89.00 stddev:1.89 (2.1%) stddev-sans-first:1.81 03:43:15 INFO - PROCESS | 1689 | Values: 90.0 85.0 90.0 86.0 89.0 86.0 89.0 87.0 89.0 86.0 03:43:15 INFO - PROCESS | 1689 | 03:43:15 INFO - PROCESS | 1689 | [#6] imaging-darkroom Cycles:10 Average:100.60 Median:94.00 stddev:17.52 (18.6%) stddev-sans-first:18.42 03:43:15 INFO - PROCESS | 1689 | Values: 94.0 93.0 94.0 107.0 149.0 94.0 93.0 95.0 93.0 94.0 03:43:15 INFO - PROCESS | 1689 | 03:43:15 INFO - PROCESS | 1689 | [#7] imaging-desaturate Cycles:10 Average:85.30 Median:85.50 stddev:1.34 (1.6%) stddev-sans-first:1.00 03:43:15 INFO - PROCESS | 1689 | Values: 88.0 86.0 84.0 86.0 83.0 85.0 85.0 85.0 85.0 86.0 03:43:15 INFO - PROCESS | 1689 | 03:43:15 INFO - PROCESS | 1689 | [#8] json-parse-financial Cycles:10 Average:54.00 Median:54.00 stddev:0.94 (1.7%) stddev-sans-first:1.00 03:43:15 INFO - PROCESS | 1689 | Values: 54.0 55.0 54.0 54.0 53.0 54.0 53.0 56.0 54.0 53.0 03:43:15 INFO - PROCESS | 1689 | 03:43:15 INFO - PROCESS | 1689 | [#9] json-stringify-tinderbox Cycles:10 Average:70.90 Median:71.50 stddev:3.28 (4.6%) stddev-sans-first:2.35 03:43:15 INFO - PROCESS | 1689 | Values: 64.0 73.0 69.0 77.0 72.0 70.0 71.0 71.0 70.0 72.0 03:43:15 INFO - PROCESS | 1689 | 03:43:15 INFO - PROCESS | 1689 | [#10] stanford-crypto-aes Cycles:10 Average:85.50 Median:83.50 stddev:6.36 (7.6%) stddev-sans-first:6.69 03:43:15 INFO - PROCESS | 1689 | Values: 83.0 84.0 87.0 81.0 83.0 82.0 84.0 91.0 101.0 79.0 03:43:15 INFO - PROCESS | 1689 | 03:43:15 INFO - PROCESS | 1689 | [#11] stanford-crypto-ccm Cycles:10 Average:141.40 Median:140.00 stddev:10.77 (7.7%) stddev-sans-first:11.09 03:43:15 INFO - PROCESS | 1689 | Values: 134.0 135.0 139.0 145.0 147.0 134.0 135.0 135.0 169.0 141.0 03:43:15 INFO - PROCESS | 1689 | 03:43:15 INFO - PROCESS | 1689 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.60 Median:164.50 stddev:20.64 (12.5%) stddev-sans-first:21.38 03:43:15 INFO - PROCESS | 1689 | Values: 160.0 164.0 165.0 163.0 162.0 165.0 163.0 219.0 203.0 162.0 03:43:15 INFO - PROCESS | 1689 | 03:43:15 INFO - PROCESS | 1689 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.00 Median:69.50 stddev:7.32 (10.5%) stddev-sans-first:7.54 03:43:15 INFO - PROCESS | 1689 | Values: 66.0 66.0 66.0 67.0 66.0 65.0 75.0 84.0 83.0 72.0 03:43:15 INFO - PROCESS | 1689 | -------- Summary: end -------- 03:43:15 INFO - PROCESS | 1689 | 03:43:16 INFO - TEST-INFO | 1689: exit 0 03:43:16 INFO - TEST-OK | kraken | took 59360ms 03:43:16 INFO - SUITE-END | took 1175s 03:43:16 INFO - Completed test suite (00:19:36) 03:43:16 INFO - javascript benchmark 03:43:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3283.720883724883, 3311.12255252358, 3324.6176689680688, 3331.5002749587575, 3387.7120444762195, 5564.944350556493, 5627.296587926509, 5687.772489100431, 5727.885442291152, 5728.656280623166, 5673.801416950408, 5707.172459993299, 5717.885642287151, 5751.856203594913, 5769.480746732793, 5125.333706618135, 5153.665011774232, 5183.455737147602, 5184.637075404723, 5259.710715910624, 5191.091558977183, 5206.869828254286, 5215.374155101393, 5260.421353651095, 5283.920741188886, 4878.170710979142, 4882.292067650206, 5257.185136303879, 5257.36911570612, 5266.34170728659, 5077.543021128079, 5231.738413079369, 5239.842804715865, 5244.213367994831, 5254.65844720092, 5170.224466330043, 5183.792648294101, 5194.532492075694, 5203.713795741232, 5209.400918894337, 5593.077142271521, 5640.2667653205235, 5686.0, 5729.455701708331, 5738.311402631669, 6047.21386219793, 6071.68038769774, 6139.355367686418, 6202.0, 6223.751049957997, 6202.162708034402, 6241.9375806241815, 6265.843353916143, 6282.0, 6306.369363063703, 5794.159846822272, 5807.1869938208265, 5815.15680226369, 5821.068629019422, 5822.737976791054, 6233.0, 6240.375962403723, 6241.594296370721, 6301.369863013662, 6301.968490157612, 6101.398774955092, 6104.962156433417, 6108.511319094462, 6117.990531562331, 6119.244515559795, 6193.411625895623, 6239.812805615838, 6245.156903818017, 6253.74985000604, 6256.843578910563, 6024.397560244028, 6078.209832721718, 6103.298120716175, 6112.144299798032, 6113.174721412642, 6148.401415631968, 6184.969075154685, 6206.379362063848, 6219.875602487925, 6226.128342032119, 4630.768461576975, 4877.146499362598, 4881.682690625098, 4881.755912204447, 4933.383327084114, 4330.805113769888, 4371.169477799208, 4398.868033958986, 4399.38408622793, 4400.361947517592, 5554.361248456398, 5577.525910297589, 5599.440055994368, 5635.633683810539, 5688.658680479184, 6155.969220153871, 6245.875082498415, 6252.5935814171935, 6265.968670156712, 6268.843278917972, 5508.91736623951, 5562.443755624405, 5595.104783234744, 5604.85987850307, 5633.887322253532, 5354.544863686552, 5367.463253674601, 5376.7042812644895, 5381.730913454312, 5391.32608423947, 6235.563510554218, 6269.0909818076125, 6273.121762953281, 6273.278572963985, 6282.968585157045, 6106.339326076981, 6111.205543279345, 6122.032243765785, 6145.033589251397, 6269.905951410641, 6234.096056071935, 6241.781537646342, 6243.0, 6262.029385445263, 6263.812085637438, 6041.848953776191, 6053.485453736482, 6089.056196289582, 6107.480864126599, 6131.84670383244, 6055.424734650202, 6119.319765821106, 6171.352008039091, 6180.072989051679, 6253.656048917172, 6066.059760737093, 6066.878662426815, 6072.193407646707, 6072.817815465543, 6108.17539632144, 4688.578027977498, 5818.505427038749, 6031.475925345497, 6055.091736239599, 6091.177691011657, 5819.447152520504, 5880.0, 5898.882022359443, 5919.85200369977, 5981.3420523743225, 6040.7583696651645, 6096.468527292138, 6116.8776624468155, 6123.425291022073, 6139.754409823557, 5763.221965034834, 5804.448577385142, 5917.91123133162, 5933.931892259435, 5945.613535120204], "value": 5656.767308584091, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [475.89530303333265, 534.1587000474253, 537.5565158744037, 546.4753836317135, 552.7429745168496, 30337.0, 39887.202255954864, 41116.58883411165, 41983.370249446285, 42248.0, 43586.0, 43878.561214387846, 43965.0, 44146.337804932875, 44220.336694949605, 8762.255208307302, 9739.707808765748, 9888.456134912576, 9889.50552472377, 9920.553575089103, 8717.259032982203, 11811.350375729331, 12036.976856967169, 12055.457504412323, 12283.815742763865, 12027.879721202764, 12099.274043557414, 12108.576199833007, 12118.333491657955, 12120.81818772719, 8805.383623146383, 9035.638574457014, 12030.759384812342, 12070.396480176043, 12083.45624446902, 8810.427322224035, 8895.644174233088, 11572.132090093193, 11829.645110646608, 11834.171607987444, 41393.58606413927, 41907.580924190675, 41940.580594193976, 41948.79025604852, 41969.37045944313, 3563.465480177968, 3595.227026189356, 3733.533308336458, 3757.4176002719623, 4247.362895565659, 28274.010409635564, 32982.0, 33230.0, 33488.49767253493, 33525.0, 3205.246767009751, 3272.5091236314743, 3292.6213485449002, 3315.6850099240532, 3316.4859446785786, 527063.0, 537345.0, 537695.0, 538276.0, 538825.999999996, 67076.32923670798, 68428.0, 68561.0, 68565.0, 68713.96929045969, 73286.63356683182, 73876.0, 73960.63019684867, 74007.0, 74049.6297518509, 16267.023978561325, 16865.831341686673, 16876.83123168777, 16884.07137607419, 16889.98660080399, 11636.301821890713, 11782.646520604396, 11870.584529541424, 12712.173708708904, 12845.0, 18511.35210267661, 18666.78665886713, 19359.047863822474, 21838.908054597523, 22033.228836990947, 8702.738917832476, 8738.86891696625, 8765.780855478537, 8776.025394286235, 8835.249003834617, 875.3085062800317, 935.6865450074301, 1042.3068659341452, 1045.9477026148813, 1053.1837825685154, 29217.707822921922, 29432.705672942997, 30417.0, 30641.0, 31361.8431907839, 999.6751055906859, 1149.8102813035923, 1215.1129675337054, 1230.723087305349, 1254.8807863252975, 16758.0, 16873.07198104116, 17027.659446810994, 17159.399421020204, 17182.39861604838, 621004.0, 624295.0, 625833.0, 626967.0, 631376.0, 58769.0, 60660.696696516236, 60829.87095451638, 60858.69570652118, 60987.69506152529, 1271.9173253738477, 1311.1018952017898, 1311.9475220991244, 1435.806166167575, 1498.0412535976984, 26977.0, 30916.381672366875, 31920.36159276848, 32264.0, 32317.192070198435, 48665.51334486751, 56284.31147065594, 56289.592760181324, 56399.0, 56416.717916410154, 627696.0, 646867.0, 647774.0, 649489.0, 650452.0, 22071.66892496646, 23481.882590587622, 26972.595411069244, 27343.72656273412, 27855.72144278612, 45484.54515454803, 46552.76723616496, 49865.0, 50548.0, 50715.49284507108, 33882.66117338795, 36740.44889326716, 38716.6128338713, 39226.0, 41925.79037104795, 39691.80154099211, 39769.40345894756, 39893.60106398899, 40267.3959890596, 41156.38265426081, 35107.29785404226, 35789.28421431609, 35835.64164358428, 35862.64137358593, 36107.81946090358, 34219.0, 35338.29323413466, 35565.82217089002, 35872.4619130708, 35952.28095438129, 24247.75752242455, 24408.0, 25875.0, 26258.343541410854, 26505.783406916515], "value": 21088.02734851888, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [326.55588399776303, 364.64811456944045, 391.2741863842571, 394.29421335808905, 412.71316435077614, 16155.75766363504, 16312.75530867038, 21459.356219313446, 21470.248541301058, 21519.462013449673, 21891.452713682167, 22549.54900901985, 22657.320280391574, 22689.432764180903, 22933.312000639966, 5832.591718579701, 5875.471207591316, 6008.969955150218, 6060.0, 6064.241969753781, 4263.040815816432, 4492.8876778080485, 5946.375630558794, 6070.332263451039, 6601.438877695402, 6849.109615749945, 6917.100776899021, 6923.688434020456, 6926.099607051076, 6937.583744975318, 6575.309592492791, 6879.58722476653, 6895.413889819371, 6897.2757860424435, 6897.827554311132, 3822.10180607557, 4104.835806567734, 6002.399760024006, 6012.669303188323, 6022.96988515059, 22791.316260512212, 26853.73146268532, 27418.5887211692, 27856.582151267747, 28332.00837970682, 2082.4689704125353, 2436.196055301746, 2459.32368598635, 2616.188981415767, 2693.0, 10653.680389588324, 11304.773904521946, 14282.78596319314, 15935.362585496567, 16580.336786528525, 1846.3260909767837, 1875.1936667233085, 1878.7412433669474, 1918.060150526246, 1959.5101224693826, 72976.90534641985, 94262.52868735613, 98111.0, 114842.0, 126786.36606817, 9604.51977401127, 11451.370174640308, 11492.54029838797, 11643.41782910851, 11794.882051179378, 14591.05158164716, 14611.92694036523, 14612.780808287884, 14622.0, 14627.561173164822, 8419.200175983271, 8616.741497755078, 8674.219320261207, 8706.564671766386, 8708.08565100668, 7360.558366498028, 7362.705491780269, 8574.099719529484, 8584.570771461402, 8744.720729726352, 4493.797779099883, 4566.88582785433, 4569.246074397687, 4602.4477062752685, 4650.116477869194, 1625.5285967069653, 1627.2758622413148, 1883.849292056632, 1911.8566107541712, 1928.4889504281377, 887.973360799177, 903.4127816919055, 931.3573634192386, 931.7763736703278, 956.6125719083782, 21483.570328593654, 22470.66294005591, 22564.66153007706, 22575.0, 22584.0, 930.4882314726873, 952.642758965388, 963.3786207895868, 995.1640621877658, 1049.286485190078, 11143.33140011602, 11215.43922803873, 11406.657800266004, 11567.652970410732, 11661.416929153509, 583634.0, 604065.0, 608548.9572552108, 618017.0, 619022.9048854727, 94706.0, 125788.0, 138334.0, 143488.0, 147019.0, 960.9135177834029, 963.6482683820356, 984.7242772023844, 1063.7978784030854, 1084.3819023156882, 20326.695099573222, 20777.168913243906, 20801.751894886354, 20804.0, 20903.477413064797, 56879.715601421725, 57283.0, 57511.0, 57528.71235643795, 58616.0, 68706.0, 77830.6108469454, 82274.76587851306, 85299.1470085316, 86057.70913436178, 6778.525503214925, 8103.7163699272605, 8108.75673729789, 8235.588220589067, 8280.337572994267, 11816.350100744197, 13041.869581304065, 13147.408366623717, 13320.400581974029, 13494.595162145151, 11398.316101033965, 11473.4263286837, 11478.88521114778, 11555.653330400102, 11710.707232318919, 11200.21598488098, 11370.31778093317, 11388.259763115371, 11416.94291528537, 11466.77066458683, 10611.779645340885, 11027.283226590245, 11795.174337796616, 11925.642230733092, 12066.939665301617, 7153.463490238357, 7501.437392195498, 7524.736634217776, 8140.959295203486, 8448.66205351779, 8394.0766515682, 10729.89270107289, 11255.212135150461, 11815.387690847123, 11833.644990650295], "value": 9803.611804808448, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1540.2529773060064, 1576.5112815027342, 1782.144570606109, 1851.1947302923238, 1931.0248324596078, 8716.389852710312, 8992.0, 9169.862452063217, 9182.173604375605, 9233.584488698008, 7969.521828690283, 8203.712870049549, 8652.264557512603, 8893.288536917047, 8987.505687187202, 2355.611324131516, 2378.940526486843, 2382.5830479666074, 2385.4632707640817, 2386.7971222446026, 2127.585120901425, 2186.0381432169875, 2226.0873042052763, 2272.749997500274, 2362.8818559072065, 2097.7272954515893, 2179.182806447582, 2294.8852557372065, 2373.193114341124, 2382.7736365045293, 2337.7779110984516, 2353.988230058856, 2358.233574088411, 2360.091364824555, 2364.775346342095, 2334.36972017556, 2378.619420892649, 2381.4760752634566, 2381.48798008429, 2382.3686723018413, 7661.272179143029, 9149.176574108362, 9163.404378715362, 9165.908340916572, 9169.862452063224, 5233.816816411445, 5446.2375267462585, 5464.644798088111, 5467.371252305956, 5472.1244600864065, 3011.6084908961916, 3642.7814331140216, 3753.9624603753887, 3760.3983362662116, 3767.7550959187565, 1069.171392171066, 1078.5308390849927, 1080.4219742437836, 1092.7158938675923, 1098.747288123728, 12070.034397248199, 12071.637850864488, 12082.456289466907, 12087.033037357078, 12108.970737487323, 4062.390641403813, 4713.080949214938, 4896.632752543503, 4901.0, 4904.705717656881, 10127.696169114792, 10135.594576217016, 10136.847947280796, 10165.186785057178, 10169.847452288222, 5434.864128396822, 5438.673679579238, 5438.7280635968045, 5442.156465747816, 5464.9180262296095, 4950.777215025332, 4980.626453016038, 4984.40187177541, 4988.6757360771435, 5027.3464449621315, 4031.334829753058, 4045.7572545647354, 4083.816228269735, 4141.751494910315, 4162.167566486714, 1293.4244261303818, 1300.4473098933104, 1302.9739405211842, 1316.2431601828987, 1322.0283091927427, 636.6371168433948, 781.9491733037335, 822.6010384963288, 882.8346582511024, 1003.5484032185545, 5611.7755289788765, 5878.470937615635, 6076.453119219292, 6091.908621370594, 6195.132681424604, 913.3104506097853, 931.03637734944, 946.2430055955346, 962.7978124593774, 979.4564016970508, 3396.9660303397145, 3757.868474603376, 4112.602702824092, 4112.691548133902, 4162.521310049383, 10657.733556661145, 10882.564697412174, 11004.614838480615, 11027.117830573536, 11038.951299626522, 9920.900790992142, 10278.640247591298, 10349.430781307101, 10876.966688164612, 10980.176486763525, 7613.352865006425, 8133.877991830127, 8392.874106888401, 8434.789130271669, 8436.957815210884, 5997.250343707036, 6101.481374083252, 6206.534509911684, 6236.688165591611, 6254.6404030238045, 10141.290109692545, 10160.216884432984, 10308.793824123326, 10403.94798026005, 10422.322549034288, 9752.219822414332, 10063.345882517613, 10163.898361016296, 10169.338992965433, 10558.8416173759, 7740.0, 7830.256125668053, 7902.802429939297, 7911.802204944923, 7922.920770792219, 8508.0, 8567.700130495403, 8587.82824343522, 8636.136386361313, 8642.78393040179, 7744.380449563909, 7778.377729781717, 7837.686492540234, 7907.9604601976625, 7947.5231486111015, 7448.9627551861895, 7585.2035536270105, 7708.306252437307, 7738.380929525511, 7862.449628525948, 7109.786706398816, 7236.927630723625, 7277.781666550012, 7288.12542494904, 7338.70645174187, 5086.567641750493, 5097.057044446789, 5112.590992720499, 5128.256402821496, 5225.895482090413, 5057.0, 5068.670536415121, 5101.877586930869, 5282.181261904411, 5283.947160528345], "value": 4666.481857866076, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [258.61595530637, 301.818908654807, 314.7026060372947, 317.6347200719173, 319.90242975892374, 5587.888242235153, 5602.663840169587, 5604.635698679583, 5610.186522954169, 5627.143042795873, 5209.687418754877, 5227.947720522794, 5455.1271796512565, 5488.506034456898, 5532.861678458041, 4180.916381672365, 4219.789010549475, 4227.682923780713, 4260.31834906415, 4266.552012038738, 4257.467816522934, 4259.339802330644, 4270.210011147932, 4279.636230920375, 4287.5069367022725, 3952.407138929155, 4071.0, 4127.484064491939, 4222.0, 4247.214265360903, 3518.384282750534, 4185.853495127686, 4204.495460544724, 4223.324268117102, 4246.299360605496, 3682.4660424238336, 3691.667749902495, 3691.8154092295276, 3703.166787472797, 3708.5907355205118, 5124.4106927703415, 5291.285676433709, 5294.258803767476, 5369.140937450028, 5413.891722165535, 3893.377059670439, 4414.602685758265, 4512.593866551993, 4525.389072475206, 4818.012307476994, 4865.2945322928335, 5671.334472797778, 5736.483716465496, 5738.311402631669, 5761.214023652639, 3167.6990685884803, 3901.9219615607813, 4010.598940105995, 4019.718619696622, 4053.493313335833, 5279.023380674587, 5925.962956482598, 5940.257467816523, 5952.166696662471, 5988.999999999956, 3889.7277190596387, 3894.6494815466745, 3921.960680419691, 4014.6186112319283, 4106.568810274878, 3740.5511338638994, 4152.252594532953, 4172.4784401949755, 4192.685548583869, 4196.958030419718, 4057.857974970862, 4670.7664616769025, 4689.976550117228, 4695.483496815347, 4701.412323459567, 5089.869730115952, 5854.272989468117, 5861.472467477947, 5892.292924849045, 5919.644821310735, 1112.8998390144925, 1276.6808297925518, 1344.475654494748, 1348.332575375176, 1376.5732622886937, 780.5667854340782, 788.4165717369106, 795.908470525897, 848.2662496940226, 850.1498501498502, 4021.908607524071, 4220.607199624116, 4383.671224658099, 4479.155736107595, 4556.020455602062, 4753.053107073567, 4941.950580494221, 5012.172991456442, 5042.856785865993, 5058.077930386503, 3779.565349984732, 3942.211557688474, 4221.725587836842, 4280.91438171241, 4500.617447516932, 2295.2081531871477, 2438.561059009395, 2651.8674066296608, 2671.3455203475273, 2677.5983602459787, 4940.728259945738, 5257.447967963385, 5267.947320526746, 5269.683818970874, 5304.549113325334, 4630.745309008037, 4633.814647414133, 4828.744526423156, 4830.686005409637, 5126.539261154667, 4529.682922195415, 4996.325496058058, 5117.232415137684, 5149.433562308218, 5203.152880727287, 4304.440422745023, 4647.86056418308, 4696.567546898229, 4806.855794326176, 4923.4584195737725, 4496.7301961882395, 4597.333386658849, 4951.77717002743, 4952.950470495249, 5051.595872330131, 4371.9344209836245, 4415.668824838086, 4829.0, 5115.7953681852305, 5118.539331460038, 3065.340951695396, 3100.1319630503494, 3476.7044801192183, 3493.253373313343, 3531.845574199685, 5016.473270306711, 5050.787810925278, 5240.397354304304, 5470.206820011155, 5784.4960310318775, 4821.54195351441, 5113.51421614946, 5192.143296356059, 5425.348958125051, 5719.799807006735, 5022.271770593316, 5560.499555040065, 5678.716064196691, 5699.458551437607, 5701.861801824316, 5237.607179461479, 5293.867653308698, 5297.390800057889, 5578.107502799695, 5667.3765885753355, 5002.623753716955, 5390.97304513475, 5732.449060168352, 5744.3394009687745, 5763.279590051244, 5578.721063946705, 5653.547716182778, 5669.291338582677, 5700.087985922399, 5705.486506214461], "value": 3967.9922383303933, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [683.8598087392085, 748.3302444312341, 769.4921351907742, 790.674200666977, 892.5715656484887, 14910.030847994873, 15674.373025078983, 16179.919100404497, 16210.351585936563, 16524.00855948644, 14865.0, 14957.850421495781, 15067.773983390258, 15097.396104155821, 15277.618059548517, 717.0785540580355, 744.4863044499306, 889.2352576783977, 892.1702816380763, 925.1950802801539, 894.6376717429418, 896.7578753736487, 897.9640814367417, 898.0974121008377, 900.0144841398658, 947.0813311088233, 947.5972711597578, 950.0736781637917, 950.909663581962, 951.110711484764, 759.8670232709254, 929.256594724218, 931.5994122527311, 965.3483898368573, 966.7148191283588, 710.1336369629096, 712.4692104382217, 713.105053158283, 713.1477883928708, 713.1727196452142, 15236.23818809055, 15250.38998440061, 15274.694506109927, 15294.0, 15296.617584560363, 12497.062720295924, 13690.93154534231, 14056.648583785487, 14065.296735163201, 14092.436302547885, 11623.07015438763, 12823.220048607525, 12863.614091577268, 13024.413901374366, 13414.798778018338, 4176.937345939813, 4606.193916064675, 4636.721796692175, 4798.616110711169, 4810.254410566367, 107644.0, 109496.0, 110952.0, 111301.99999999919, 111503.44248278788, 11084.279521831055, 11225.158113141384, 11255.549778008788, 11280.492377843017, 11619.302841829518, 18213.271469141087, 22298.55402891933, 22305.10779568799, 22318.888405557867, 22477.4380640482, 7999.0801057877925, 8002.719904803421, 8114.63484143215, 8148.796280092926, 8216.876746848802, 4397.2085024695225, 4452.48796388413, 4506.752128632891, 4566.84016059443, 4639.211334073216, 3539.203679172217, 3652.6164752700583, 3657.9940516358215, 3658.451232315174, 3668.963310366862, 546.9671819690832, 635.5363270177551, 691.2603514239815, 701.2041333086881, 715.6850985566334, 585.6690969602246, 613.6195558753512, 650.7592190889306, 694.905523800017, 707.6992278281709, 9742.0, 9765.902340976641, 9842.360246583949, 9917.950410248048, 9989.400635961865, 567.5884983387011, 581.5522048022999, 646.6766616691655, 650.9446697030711, 659.2583343738295, 4563.520830312836, 4811.639127065414, 4813.542713442217, 4823.4297952881125, 4936.3829521309835, 111275.0, 134781.0, 139658.0, 140639.89040164405, 147469.0, 136962.0, 138121.3093934524, 138297.0, 139874.20251595115, 140426.0, 89362.0, 120337.0, 120585.0, 121433.0, 121574.39212804056, 9231.169194772503, 9234.815303694022, 9345.0, 9387.859182112137, 9423.811523769624, 53118.0, 64168.0, 65205.8492688414, 65782.34217657761, 67222.0, 91551.0, 96807.06385872103, 97682.0, 101266.0, 102624.46063309205, 11597.0, 12078.27530348182, 12152.1493495458, 12317.398738163982, 12866.292353920624, 11873.881261187278, 13464.596062118153, 13687.657808554468, 13959.371828267955, 13981.580552583438, 11422.771544568895, 11621.709457263636, 13151.079424440197, 13800.585982420544, 14228.288585570886, 13198.634348241138, 13286.667833304246, 14140.575782726535, 14175.929120354333, 14947.0, 11374.20380573385, 13874.098183618033, 14229.430822767388, 14545.85454145445, 14892.553223403314, 11059.474909803255, 11381.43092845338, 11597.536098555964, 11801.468933898166, 12012.699682508008, 10665.733356666145, 10757.13942884583, 10789.676309710721, 10803.945980270362, 10914.290571112851], "value": 7978.788560098487, "unit": "score"}], "name": "dromaeo_css", "value": 7463.161907056517, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 113.0, 113.0, 112.0, 112.0, 112.0, 112.0, 113.0, 112.0, 112.0], "value": 111.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 107.0, 106.0, 161.0, 107.0, 107.0, 127.0, 108.0, 140.0, 123.0], "value": 122.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 182.0, 185.0, 185.0, 183.0, 185.0, 187.0, 194.0, 261.0, 186.0], "value": 193.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 74.0, 58.0, 109.0, 58.0, 57.0, 61.0, 67.0, 95.0, 78.0], "value": 73.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 87.0, 78.0, 69.0, 84.0, 96.0, 121.0, 70.0, 67.0, 101.0], "value": 85.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 85.0, 90.0, 86.0, 89.0, 86.0, 89.0, 87.0, 89.0, 86.0], "value": 87.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 107.0, 149.0, 94.0, 93.0, 95.0, 93.0, 94.0], "value": 100.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 84.0, 86.0, 83.0, 85.0, 85.0, 85.0, 85.0, 86.0], "value": 85.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 55.0, 54.0, 54.0, 53.0, 54.0, 53.0, 56.0, 54.0, 53.0], "value": 54.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 73.0, 69.0, 77.0, 72.0, 70.0, 71.0, 71.0, 70.0, 72.0], "value": 70.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 87.0, 81.0, 83.0, 82.0, 84.0, 91.0, 101.0, 79.0], "value": 85.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 135.0, 139.0, 145.0, 147.0, 134.0, 135.0, 135.0, 169.0, 141.0], "value": 141.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 164.0, 165.0, 163.0, 162.0, 165.0, 163.0, 219.0, 203.0, 162.0], "value": 172.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 67.0, 66.0, 65.0, 75.0, 84.0, 83.0, 72.0], "value": 71.0, "unit": "score"}], "name": "kraken", "value": 1455.2, "alertThreshold": 2.0}]} 03:43:16 INFO - Return code: 0 03:43:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:43:16 INFO - # TBPL SUCCESS # 03:43:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:43:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:43:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:43:16 INFO - 2016-07-06 03:43:16.944 screenresolution[1693:10942] starting screenresolution argv=screenresolution get 03:43:16 INFO - 2016-07-06 03:43:16.947 screenresolution[1693:10942] Display 0: 1600x1200x32@60 03:43:16 INFO - 2016-07-06 03:43:16.953 screenresolution[1694:10945] starting screenresolution argv=screenresolution list 03:43:16 INFO - Available Modes on Display 0 03:43:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:43:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:43:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:43:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:43:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:43:17 INFO - Intel Iris: 03:43:17 INFO - Chipset Model: Intel Iris 03:43:17 INFO - Type: GPU 03:43:17 INFO - Bus: Built-In 03:43:17 INFO - VRAM (Dynamic, Max): 1536 MB 03:43:17 INFO - Vendor: Intel (0x8086) 03:43:17 INFO - Device ID: 0x0a2e 03:43:17 INFO - Revision ID: 0x0009 03:43:17 INFO - Displays: 03:43:17 INFO - Display: 03:43:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:43:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:43:17 INFO - Display Serial Number: 1600x1200 60 03:43:17 INFO - Main Display: Yes 03:43:17 INFO - Mirror: Off 03:43:17 INFO - Online: Yes 03:43:17 INFO - Rotation: Supported 03:43:17 INFO - Return code: 0 03:43:17 INFO - Running post-action listener: _resource_record_post_action 03:43:17 INFO - ##### 03:43:17 INFO - ##### Finished run-tests step (success) 03:43:17 INFO - ##### 03:43:17 INFO - Running post-run listener: _resource_record_post_run 03:43:17 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 32837120; Write bytes: 706997760; Read time: 324; Write time: 2288 03:43:17 INFO - TinderboxPrint: CPU usage
53.7% 03:43:17 INFO - TinderboxPrint: I/O read bytes / time
32,837,120 / 324 03:43:17 INFO - TinderboxPrint: I/O write bytes / time
706,997,760 / 2,288 03:43:17 INFO - TinderboxPrint: CPU idle
2,246.2 (46.3%) 03:43:17 INFO - TinderboxPrint: CPU system
967.1 (19.9%) 03:43:17 INFO - TinderboxPrint: CPU user
1,636.4 (33.7%) 03:43:17 INFO - TinderboxPrint: Swap in / out
436,248,576 / 0 03:43:17 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 404961792; Write bytes: 398738944; Read time: 30416; Write time: 750 03:43:17 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 30752256; Write bytes: 296441856; Read time: 301; Write time: 1504 03:43:17 INFO - Running post-run listener: _upload_blobber_files 03:43:17 INFO - Blob upload gear active. 03:43:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:43:17 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 03:43:17 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'] 03:43:17 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 03:43:18 INFO - (blobuploader) - INFO - Open directory for files ... 03:43:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:43:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:43:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:43:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:43:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:43:19 INFO - (blobuploader) - INFO - Done attempting. 03:43:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:43:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:43:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:43:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:43:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:43:20 INFO - (blobuploader) - INFO - Done attempting. 03:43:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:43:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:43:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:43:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:43:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:43:22 INFO - (blobuploader) - INFO - Done attempting. 03:43:22 INFO - (blobuploader) - INFO - Iteration through files over. 03:43:22 INFO - Return code: 0 03:43:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:43:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:43:22 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0295f09c2410892d3fbb60f8db49898c6692f66193c34395a9e9cd3738f9b3b9394e50f688dfe8ccd118aea7f6fb7c7dc318b1b10fde2cef731b22a6772d1ee3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/15c96efb473d8b19edcba984a6c5e63903e98ca51379ce670cb50578382fc59b1eb81e977dff750f976d29b7aa467818294058814aacdd5e1a28214ca61b0621", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3058266d8a102f2ccf4698e3de0fa67e1f5d97c8b5b32f70b070dc8ba5ba495fb67e0328edcbf47659bbfe1c66bfa783ff640fef122b784db361fc4165e559d7"} 03:43:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:43:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:43:22 INFO - Contents: 03:43:22 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0295f09c2410892d3fbb60f8db49898c6692f66193c34395a9e9cd3738f9b3b9394e50f688dfe8ccd118aea7f6fb7c7dc318b1b10fde2cef731b22a6772d1ee3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/15c96efb473d8b19edcba984a6c5e63903e98ca51379ce670cb50578382fc59b1eb81e977dff750f976d29b7aa467818294058814aacdd5e1a28214ca61b0621", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3058266d8a102f2ccf4698e3de0fa67e1f5d97c8b5b32f70b070dc8ba5ba495fb67e0328edcbf47659bbfe1c66bfa783ff640fef122b784db361fc4165e559d7"} 03:43:22 INFO - Running post-run listener: copy_logs_to_upload_dir 03:43:22 INFO - Copying logs to upload dir... 03:43:22 INFO - mkdir: /builds/slave/test/build/upload/logs 03:43:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1278.675116 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-07-06 03:43:22.695166) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-06 03:43:22.699403) ========= 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.edNhbqYFTx/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.XaKdp7AWFU/Listeners TMPDIR=/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0295f09c2410892d3fbb60f8db49898c6692f66193c34395a9e9cd3738f9b3b9394e50f688dfe8ccd118aea7f6fb7c7dc318b1b10fde2cef731b22a6772d1ee3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/15c96efb473d8b19edcba984a6c5e63903e98ca51379ce670cb50578382fc59b1eb81e977dff750f976d29b7aa467818294058814aacdd5e1a28214ca61b0621", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3058266d8a102f2ccf4698e3de0fa67e1f5d97c8b5b32f70b070dc8ba5ba495fb67e0328edcbf47659bbfe1c66bfa783ff640fef122b784db361fc4165e559d7"} build_url:https://queue.taskcluster.net/v1/task/apSptfXeRreQkdsaxhjddQ/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011164 build_url: 'https://queue.taskcluster.net/v1/task/apSptfXeRreQkdsaxhjddQ/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/0295f09c2410892d3fbb60f8db49898c6692f66193c34395a9e9cd3738f9b3b9394e50f688dfe8ccd118aea7f6fb7c7dc318b1b10fde2cef731b22a6772d1ee3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/15c96efb473d8b19edcba984a6c5e63903e98ca51379ce670cb50578382fc59b1eb81e977dff750f976d29b7aa467818294058814aacdd5e1a28214ca61b0621", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3058266d8a102f2ccf4698e3de0fa67e1f5d97c8b5b32f70b070dc8ba5ba495fb67e0328edcbf47659bbfe1c66bfa783ff640fef122b784db361fc4165e559d7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-06 03:43:22.735204) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 03:43:22.735796) ========= 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.edNhbqYFTx/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.XaKdp7AWFU/Listeners TMPDIR=/var/folders/d8/n42hj_ld0q5d1pgs8tfpv7hr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005149 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 03:43:22.787332) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-06 03:43:22.787876) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-06 03:43:22.788582) ========= ========= Total master_lag: 0.28 =========