builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0116 starttime: 1461754148.25 results: success (0) buildid: 20160425042155 builduid: 3cafbae6ee4445cbb07d61c2d398d690 revision: f328ac32af6a3d0ce1593c41fd18edf7216a1652 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-27 03:49:08.246064) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-27 03:49:08.246517) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-27 03:49:08.246815) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-27 03:49:08.270866) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 03:49:08.271243) ========= 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.troASljsGd/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.UnJSvazDkE/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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-04-27 03:49:08-- 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% 8.97M=0.001s 2016-04-27 03:49:08 (8.97 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.105387 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 03:49:08.397207) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 03:49:08.397517) ========= 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.troASljsGd/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.UnJSvazDkE/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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.026037 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 03:49:08.445658) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-27 03:49:08.449081) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev f328ac32af6a3d0ce1593c41fd18edf7216a1652 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev f328ac32af6a3d0ce1593c41fd18edf7216a1652 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.troASljsGd/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.UnJSvazDkE/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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-04-27 03:49:08,527 truncating revision to first 12 chars 2016-04-27 03:49:08,527 Setting DEBUG logging. 2016-04-27 03:49:08,527 attempt 1/10 2016-04-27 03:49:08,527 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/f328ac32af6a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-27 03:49:09,554 unpacking tar archive at: mozilla-aurora-f328ac32af6a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.278403 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-27 03:49:09.744366) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-27 03:49:09.744684) ========= script_repo_revision: f328ac32af6a3d0ce1593c41fd18edf7216a1652 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-27 03:49:09.748144) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-27 03:49:09.748425) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-27 03:49:09.764631) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2016-04-27 03:49:09.765065) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.troASljsGd/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.UnJSvazDkE/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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:49:09 INFO - MultiFileLogger online at 20160427 03:49:09 in /builds/slave/test 03:49:09 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:49:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:49:09 INFO - {'append_to_log': False, 03:49:09 INFO - 'base_work_dir': '/builds/slave/test', 03:49:09 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:49:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:49:09 INFO - 'branch': 'Mozilla-Aurora', 03:49:09 INFO - 'buildbot_json_path': 'buildprops.json', 03:49:09 INFO - 'config_files': ('talos/mac_config.py',), 03:49:09 INFO - 'default_actions': ('clobber', 03:49:09 INFO - 'read-buildbot-config', 03:49:09 INFO - 'download-and-extract', 03:49:09 INFO - 'populate-webroot', 03:49:09 INFO - 'create-virtualenv', 03:49:09 INFO - 'install', 03:49:09 INFO - 'run-tests'), 03:49:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:49:09 INFO - 'download_minidump_stackwalk': True, 03:49:09 INFO - 'download_symbols': 'ondemand', 03:49:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:49:09 INFO - 'tooltool.py': '/tools/tooltool.py', 03:49:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:49:09 INFO - '/tools/misc-python/virtualenv.py')}, 03:49:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:49:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:49:09 INFO - 'installer_path': 'installer.exe', 03:49:09 INFO - 'log_level': 'info', 03:49:09 INFO - 'log_name': 'talos', 03:49:09 INFO - 'log_to_console': True, 03:49:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:49:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:49:09 INFO - 'opt_config_files': (), 03:49:09 INFO - 'pip_index': False, 03:49:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:49:09 INFO - 'cmd': ('bash', 03:49:09 INFO - '-c', 03:49:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:49:09 INFO - 'enabled': True, 03:49:09 INFO - 'halt_on_failure': False, 03:49:09 INFO - 'name': 'check_screen_resolution'},), 03:49:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:49:09 INFO - 'cmd': ('bash', 03:49:09 INFO - '-c', 03:49:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:49:09 INFO - 'enabled': True, 03:49:09 INFO - 'halt_on_failure': False, 03:49:09 INFO - 'name': 'check_screen_resolution'},), 03:49:09 INFO - 'run_cmd_checks_enabled': True, 03:49:09 INFO - 'sps_profile': False, 03:49:09 INFO - 'sps_profile_interval': 0, 03:49:09 INFO - 'suite': 'dromaeojs', 03:49:09 INFO - 'system_bits': '32', 03:49:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:49:09 INFO - 'title': 't-yosemite-r7-0116', 03:49:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:49:09 INFO - 'use_talos_json': True, 03:49:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:49:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:49:09 INFO - 'work_dir': 'build'} 03:49:09 INFO - ##### 03:49:09 INFO - ##### Running clobber step. 03:49:09 INFO - ##### 03:49:09 INFO - Running pre-action listener: _resource_record_pre_action 03:49:09 INFO - Running main action method: clobber 03:49:09 INFO - rmtree: /builds/slave/test/build 03:49:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:49:11 INFO - Running post-action listener: _resource_record_post_action 03:49:11 INFO - ##### 03:49:11 INFO - ##### Running read-buildbot-config step. 03:49:11 INFO - ##### 03:49:11 INFO - Running pre-action listener: _resource_record_pre_action 03:49:11 INFO - Running main action method: read_buildbot_config 03:49:11 INFO - Using buildbot properties: 03:49:11 INFO - { 03:49:11 INFO - "project": "", 03:49:11 INFO - "product": "firefox", 03:49:11 INFO - "script_repo_revision": "production", 03:49:11 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 03:49:11 INFO - "repository": "", 03:49:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 03:49:11 INFO - "buildid": "20160425042155", 03:49:11 INFO - "pgo_build": "False", 03:49:11 INFO - "basedir": "/builds/slave/test", 03:49:11 INFO - "buildnumber": 325, 03:49:11 INFO - "slavename": "t-yosemite-r7-0116", 03:49:11 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 03:49:11 INFO - "platform": "yosemite_r7", 03:49:11 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 03:49:11 INFO - "branch": "mozilla-aurora", 03:49:11 INFO - "repo_path": "releases/mozilla-aurora", 03:49:11 INFO - "revision": "f328ac32af6a3d0ce1593c41fd18edf7216a1652", 03:49:11 INFO - "stage_platform": "macosx64", 03:49:11 INFO - "builduid": "3cafbae6ee4445cbb07d61c2d398d690", 03:49:11 INFO - "slavebuilddir": "test" 03:49:11 INFO - } 03:49:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg. 03:49:11 INFO - Running post-action listener: _resource_record_post_action 03:49:11 INFO - ##### 03:49:11 INFO - ##### Running download-and-extract step. 03:49:11 INFO - ##### 03:49:11 INFO - Running pre-action listener: _resource_record_pre_action 03:49:11 INFO - Running main action method: download_and_extract 03:49:11 INFO - mkdir: /builds/slave/test/build/tests 03:49:11 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:49:11 INFO - https://queue.taskcluster.net/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 03:49:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 03:49:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 03:49:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 03:49:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json'}, attempt #1 03:49:12 INFO - Downloaded 1277 bytes. 03:49:12 INFO - Reading from file /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 03:49:12 INFO - Using the following test package requirements: 03:49:12 INFO - {u'common': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 03:49:12 INFO - u'cppunittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:49:12 INFO - u'firefox-47.0a2.en-US.mac.cppunittest.tests.zip'], 03:49:12 INFO - u'gtest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:49:12 INFO - u'firefox-47.0a2.en-US.mac.gtest.tests.zip'], 03:49:12 INFO - u'jittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:49:12 INFO - u'jsshell-mac.zip'], 03:49:12 INFO - u'mochitest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:49:12 INFO - u'firefox-47.0a2.en-US.mac.mochitest.tests.zip'], 03:49:12 INFO - u'mozbase': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 03:49:12 INFO - u'reftest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:49:12 INFO - u'firefox-47.0a2.en-US.mac.reftest.tests.zip'], 03:49:12 INFO - u'talos': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:49:12 INFO - u'firefox-47.0a2.en-US.mac.talos.tests.zip'], 03:49:12 INFO - u'web-platform': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:49:12 INFO - u'firefox-47.0a2.en-US.mac.web-platform.tests.zip'], 03:49:12 INFO - u'webapprt': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 03:49:12 INFO - u'xpcshell': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:49:12 INFO - u'firefox-47.0a2.en-US.mac.xpcshell.tests.zip']} 03:49:12 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip'] for test suite category: common 03:49:12 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:49:12 INFO - https://queue.taskcluster.net/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:49:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 03:49:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 03:49:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 03:49:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:49:13 INFO - Downloaded 17919003 bytes. 03:49:13 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:49:13 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:49:16 INFO - Return code: 0 03:49:16 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip', u'firefox-47.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 03:49:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:49:16 INFO - https://queue.taskcluster.net/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:49:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 03:49:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 03:49:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 03:49:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:49:18 INFO - Downloaded 17919003 bytes. 03:49:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:49:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:49:21 INFO - Return code: 0 03:49: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:49:21 INFO - https://queue.taskcluster.net/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 03:49:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 03:49:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 03:49:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip 03:49:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip'}, attempt #1 03:49:22 INFO - Downloaded 10845194 bytes. 03:49:22 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:49:22 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 03:49:22 INFO - Return code: 0 03:49:22 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:49:22 INFO - https://queue.taskcluster.net/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 03:49:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 03:49:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 03:49:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg 03:49:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg'}, attempt #1 03:49:26 INFO - Downloaded 115766844 bytes. 03:49:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 03:49:26 INFO - mkdir: /builds/slave/test/properties 03:49:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:49:26 INFO - Writing to file /builds/slave/test/properties/build_url 03:49:26 INFO - Contents: 03:49:26 INFO - build_url:https://queue.taskcluster.net/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 03:49:27 INFO - Running post-action listener: _resource_record_post_action 03:49:27 INFO - Running post-action listener: set_extra_try_arguments 03:49:27 INFO - ##### 03:49:27 INFO - ##### Running populate-webroot step. 03:49:27 INFO - ##### 03:49:27 INFO - Running pre-action listener: _resource_record_pre_action 03:49:27 INFO - Running main action method: populate_webroot 03:49:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:49:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:49:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:49:27 INFO - '--tppagecycles', 03:49:27 INFO - '7'], 03:49:27 INFO - 'tests': ['tsvgm']}}, 03:49:27 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 03:49:27 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 03:49:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:49:27 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 03:49:27 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 03:49:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:49:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:49:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:49:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:49:27 INFO - 'talos_options': ['--e10s'], 03:49:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:49:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:49:27 INFO - 'tests': ['damp', 'tps']}, 03:49:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:49:27 INFO - 'talos_options': ['--e10s'], 03:49:27 INFO - 'tests': ['damp', 'tps']}, 03:49:27 INFO - 'g3': {'tests': ['dromaeo_dom']}, 03:49:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:49:27 INFO - 'other': {'tests': ['a11yr', 03:49:27 INFO - 'ts_paint', 03:49:27 INFO - 'tpaint', 03:49:27 INFO - 'sessionrestore', 03:49:27 INFO - 'sessionrestore_no_auto_restore', 03:49:27 INFO - 'tabpaint']}, 03:49:27 INFO - 'other-e10s': {'talos_options': ['--e10s'], 03:49:27 INFO - 'tests': ['a11yr', 03:49:27 INFO - 'ts_paint', 03:49:27 INFO - 'tpaint', 03:49:27 INFO - 'sessionrestore', 03:49:27 INFO - 'sessionrestore_no_auto_restore', 03:49:27 INFO - 'tabpaint']}, 03:49:27 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 03:49:27 INFO - 'tests': ['a11yr', 03:49:27 INFO - 'ts_paint', 03:49:27 INFO - 'tpaint', 03:49:27 INFO - 'sessionrestore', 03:49:27 INFO - 'sessionrestore_no_auto_restore', 03:49:27 INFO - 'tabpaint']}, 03:49:27 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 03:49:27 INFO - 'tests': ['a11yr', 03:49:27 INFO - 'ts_paint', 03:49:27 INFO - 'tpaint', 03:49:27 INFO - 'sessionrestore', 03:49:27 INFO - 'sessionrestore_no_auto_restore', 03:49:27 INFO - 'tabpaint']}, 03:49:27 INFO - 'other_l64': {'tests': ['a11yr', 03:49:27 INFO - 'ts_paint', 03:49:27 INFO - 'tpaint', 03:49:27 INFO - 'sessionrestore', 03:49:27 INFO - 'sessionrestore_no_auto_restore', 03:49:27 INFO - 'tabpaint']}, 03:49:27 INFO - 'other_nol64': {'tests': ['a11yr', 03:49:27 INFO - 'ts_paint', 03:49:27 INFO - 'tpaint', 03:49:27 INFO - 'sessionrestore', 03:49:27 INFO - 'sessionrestore_no_auto_restore', 03:49:27 INFO - 'tabpaint']}, 03:49:27 INFO - 'svgr': {'tests': ['tsvgx', 03:49:27 INFO - 'tsvgr_opacity', 03:49:27 INFO - 'tart', 03:49:27 INFO - 'tscrollx', 03:49:27 INFO - 'cart']}, 03:49:27 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 03:49:27 INFO - 'tests': ['tsvgx', 03:49:27 INFO - 'tsvgr_opacity', 03:49:27 INFO - 'tart', 03:49:27 INFO - 'tscrollx', 03:49:27 INFO - 'cart']}, 03:49:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:49:27 INFO - 'tests': ['tp5o']}, 03:49:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:49:27 INFO - 'talos_options': ['--e10s'], 03:49:27 INFO - 'tests': ['tp5o']}, 03:49:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:49:27 INFO - 'talos_options': ['--xperf_path', 03:49:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:49:27 INFO - 'tests': ['tp5n']}, 03:49:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:49:27 INFO - 'talos_options': ['--e10s', 03:49:27 INFO - '--xperf_path', 03:49:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:49:27 INFO - 'tests': ['tp5n']}}, 03:49:27 INFO - 'talos.zip': {'path': '', 03:49:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:49:27 INFO - Running post-action listener: _resource_record_post_action 03:49:27 INFO - ##### 03:49:27 INFO - ##### Running create-virtualenv step. 03:49:27 INFO - ##### 03:49:27 INFO - Running pre-action listener: _resource_record_pre_action 03:49:27 INFO - Running main action method: create_virtualenv 03:49:27 INFO - Creating virtualenv /builds/slave/test/build/venv 03:49:27 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:49:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:49:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:49:27 INFO - Using real prefix '/tools/python27' 03:49:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:49:28 INFO - Installing distribute.............................................................................................................................................................................................done. 03:49:30 INFO - Installing pip.................done. 03:49:30 INFO - Return code: 0 03:49:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:49:30 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:49:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:49:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:49:30 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:49:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:49:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:49:30 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 0x10eaa7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaae4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcf9cd8190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e94bd30>, '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 0x10e7afdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e942b20>, '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.troASljsGd/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.UnJSvazDkE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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:49:30 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:49:30 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:49:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.troASljsGd/Render', 03:49:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:49:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:49:30 INFO - 'HOME': '/Users/cltbld', 03:49:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:49:30 INFO - 'LOGNAME': 'cltbld', 03:49:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:49:30 INFO - 'MOZ_NO_REMOTE': '1', 03:49:30 INFO - 'NO_EM_RESTART': '1', 03:49:30 INFO - 'PAGER': '/bin/cat', 03:49:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:49:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:49:30 INFO - 'PWD': '/builds/slave/test', 03:49:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:49:30 INFO - 'SHELL': '/bin/bash', 03:49:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UnJSvazDkE/Listeners', 03:49:30 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 03:49:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:49:30 INFO - 'USER': 'cltbld', 03:49:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:49:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:49:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:49:30 INFO - 'XPC_FLAGS': '0x0', 03:49:30 INFO - 'XPC_SERVICE_NAME': '0', 03:49:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:49:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:49:31 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:49:31 INFO - Cleaning up... 03:49:31 INFO - Return code: 0 03:49:31 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 03:49:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:49:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:49:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:49:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:49:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:49:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:49:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10eaa7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaae4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcf9cd8190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e94bd30>, '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 0x10e7afdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e942b20>, '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.troASljsGd/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.UnJSvazDkE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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:49:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 03:49:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 03:49:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.troASljsGd/Render', 03:49:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:49:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:49:31 INFO - 'HOME': '/Users/cltbld', 03:49:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:49:31 INFO - 'LOGNAME': 'cltbld', 03:49:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:49:31 INFO - 'MOZ_NO_REMOTE': '1', 03:49:31 INFO - 'NO_EM_RESTART': '1', 03:49:31 INFO - 'PAGER': '/bin/cat', 03:49:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:49:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:49:31 INFO - 'PWD': '/builds/slave/test', 03:49:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:49:31 INFO - 'SHELL': '/bin/bash', 03:49:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UnJSvazDkE/Listeners', 03:49:31 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 03:49:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:49:31 INFO - 'USER': 'cltbld', 03:49:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:49:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:49:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:49:31 INFO - 'XPC_FLAGS': '0x0', 03:49:31 INFO - 'XPC_SERVICE_NAME': '0', 03:49:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:49:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:49:31 INFO - Downloading/unpacking psutil>=0.7.1 03:49:31 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:49:31 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:49:31 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:49:31 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:49:31 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:49:31 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:49:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:49:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:49:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:49:34 INFO - Installing collected packages: psutil 03:49:34 INFO - Running setup.py install for psutil 03:49:34 INFO - building 'psutil._psutil_osx' extension 03:49:34 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:49:35 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:49:35 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:49:35 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:49:35 INFO - building 'psutil._psutil_posix' extension 03:49:35 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:49:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:49:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:49:35 INFO - ^ 03:49:35 INFO - 1 warning generated. 03:49:35 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:49:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:49:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:49:35 INFO - Successfully installed psutil 03:49:35 INFO - Cleaning up... 03:49:35 INFO - Return code: 0 03:49:35 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 03:49:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:49:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:49:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:49:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:49:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:49:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:49:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10eaa7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaae4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcf9cd8190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e94bd30>, '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 0x10e7afdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e942b20>, '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.troASljsGd/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.UnJSvazDkE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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:49:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 03:49:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 03:49:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.troASljsGd/Render', 03:49:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:49:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:49:35 INFO - 'HOME': '/Users/cltbld', 03:49:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:49:35 INFO - 'LOGNAME': 'cltbld', 03:49:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:49:35 INFO - 'MOZ_NO_REMOTE': '1', 03:49:35 INFO - 'NO_EM_RESTART': '1', 03:49:35 INFO - 'PAGER': '/bin/cat', 03:49:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:49:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:49:35 INFO - 'PWD': '/builds/slave/test', 03:49:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:49:35 INFO - 'SHELL': '/bin/bash', 03:49:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UnJSvazDkE/Listeners', 03:49:35 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 03:49:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:49:35 INFO - 'USER': 'cltbld', 03:49:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:49:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:49:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:49:35 INFO - 'XPC_FLAGS': '0x0', 03:49:35 INFO - 'XPC_SERVICE_NAME': '0', 03:49:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:49:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:49:35 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 03:49:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:49:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:49:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:49:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:49:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:49:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:49:38 INFO - Downloading mozsystemmonitor-0.0.tar.gz 03:49:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:49:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 03:49:38 INFO - Installing collected packages: mozsystemmonitor 03:49:38 INFO - Running setup.py install for mozsystemmonitor 03:49:38 INFO - Successfully installed mozsystemmonitor 03:49:38 INFO - Cleaning up... 03:49:38 INFO - Return code: 0 03:49:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:49: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:49:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:49:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:49: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:49:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:49:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:49: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', '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 0x10eaa7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaae4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcf9cd8190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e94bd30>, '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 0x10e7afdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e942b20>, '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.troASljsGd/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.UnJSvazDkE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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:49: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:49: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 blobuploader==1.2.4 03:49:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.troASljsGd/Render', 03:49:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:49:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:49:38 INFO - 'HOME': '/Users/cltbld', 03:49:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:49:38 INFO - 'LOGNAME': 'cltbld', 03:49:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:49:38 INFO - 'MOZ_NO_REMOTE': '1', 03:49:38 INFO - 'NO_EM_RESTART': '1', 03:49:38 INFO - 'PAGER': '/bin/cat', 03:49:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:49:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:49:38 INFO - 'PWD': '/builds/slave/test', 03:49:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:49:38 INFO - 'SHELL': '/bin/bash', 03:49:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UnJSvazDkE/Listeners', 03:49:38 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 03:49:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:49:38 INFO - 'USER': 'cltbld', 03:49:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:49:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:49:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:49:38 INFO - 'XPC_FLAGS': '0x0', 03:49:38 INFO - 'XPC_SERVICE_NAME': '0', 03:49:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:49:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:49:38 INFO - Downloading/unpacking blobuploader==1.2.4 03:49:38 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:49:38 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:49:38 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:49:38 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:49:38 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:49:38 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:49:41 INFO - Downloading blobuploader-1.2.4.tar.gz 03:49:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:49:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:49:41 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:49:41 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:49:41 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:49:41 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:49:41 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:49:41 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:49:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:49:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:49:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:49:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:49:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:49:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:49:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:49:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:49:42 INFO - Downloading docopt-0.6.1.tar.gz 03:49:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:49:42 INFO - Installing collected packages: blobuploader, requests, docopt 03:49:42 INFO - Running setup.py install for blobuploader 03:49:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:49:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:49:42 INFO - Running setup.py install for requests 03:49:43 INFO - Running setup.py install for docopt 03:49:43 INFO - Successfully installed blobuploader requests docopt 03:49:43 INFO - Cleaning up... 03:49:43 INFO - Return code: 0 03:49:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:49:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:49:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:49:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:49:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:49:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:49:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:49:43 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 0x10eaa7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaae4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcf9cd8190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e94bd30>, '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 0x10e7afdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e942b20>, '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.troASljsGd/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.UnJSvazDkE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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:49:43 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:49:43 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:49:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.troASljsGd/Render', 03:49:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:49:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:49:43 INFO - 'HOME': '/Users/cltbld', 03:49:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:49:43 INFO - 'LOGNAME': 'cltbld', 03:49:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:49:43 INFO - 'MOZ_NO_REMOTE': '1', 03:49:43 INFO - 'NO_EM_RESTART': '1', 03:49:43 INFO - 'PAGER': '/bin/cat', 03:49:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:49:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:49:43 INFO - 'PWD': '/builds/slave/test', 03:49:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:49:43 INFO - 'SHELL': '/bin/bash', 03:49:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UnJSvazDkE/Listeners', 03:49:43 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 03:49:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:49:43 INFO - 'USER': 'cltbld', 03:49:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:49:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:49:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:49:43 INFO - 'XPC_FLAGS': '0x0', 03:49:43 INFO - 'XPC_SERVICE_NAME': '0', 03:49:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:49:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:49:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:49:43 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-F1jeuW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:49:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:49:43 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-sMBThD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:49:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:49:43 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-nnbTk2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:49:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:49:44 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-1OFP7B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:49:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:49:44 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-4cfxtf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:49:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:49:44 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-l42FV7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:49:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:49:44 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-6SJoLu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:49:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:49:44 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-pcrvxk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:49:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:49:44 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-ek4LKp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:49:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:49:44 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-uoUWOF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:49:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:49:44 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-F0Esz8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:49:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:49:44 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-AIpA3d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:49:45 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-j2OooZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:49:45 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-zci78D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:49:45 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-IS1FGm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:49:45 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-lCimqJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:49:45 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-18LZuf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:49:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:49:45 INFO - Running setup.py install for manifestparser 03:49:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:49:45 INFO - Running setup.py install for mozcrash 03:49:45 INFO - Running setup.py install for mozdebug 03:49:45 INFO - Running setup.py install for mozdevice 03:49:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:49:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:49:46 INFO - Running setup.py install for mozfile 03:49:46 INFO - Running setup.py install for mozhttpd 03:49:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:49:46 INFO - Running setup.py install for mozinfo 03:49:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:49:46 INFO - Running setup.py install for mozInstall 03:49:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:49:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:49:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:49:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:49:46 INFO - Running setup.py install for mozleak 03:49:46 INFO - Running setup.py install for mozlog 03:49:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:49:47 INFO - Running setup.py install for moznetwork 03:49:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:49:47 INFO - Running setup.py install for mozprocess 03:49:47 INFO - Running setup.py install for mozprofile 03:49:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:49:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:49:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:49:47 INFO - Running setup.py install for mozrunner 03:49:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:49:47 INFO - Running setup.py install for mozscreenshot 03:49:47 INFO - Running setup.py install for moztest 03:49:47 INFO - Running setup.py install for mozversion 03:49:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:49:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:49:48 INFO - Cleaning up... 03:49:48 INFO - Return code: 0 03:49:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:49:48 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:49:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:49:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:49:48 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:49:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:49:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:49:48 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 0x10eaa7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaae4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcf9cd8190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e94bd30>, '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 0x10e7afdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e942b20>, '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.troASljsGd/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.UnJSvazDkE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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:49:48 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:49:48 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:49:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.troASljsGd/Render', 03:49:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:49:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:49:48 INFO - 'HOME': '/Users/cltbld', 03:49:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:49:48 INFO - 'LOGNAME': 'cltbld', 03:49:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:49:48 INFO - 'MOZ_NO_REMOTE': '1', 03:49:48 INFO - 'NO_EM_RESTART': '1', 03:49:48 INFO - 'PAGER': '/bin/cat', 03:49:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:49:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:49:48 INFO - 'PWD': '/builds/slave/test', 03:49:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:49:48 INFO - 'SHELL': '/bin/bash', 03:49:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UnJSvazDkE/Listeners', 03:49:48 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 03:49:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:49:48 INFO - 'USER': 'cltbld', 03:49:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:49:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:49:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:49:48 INFO - 'XPC_FLAGS': '0x0', 03:49:48 INFO - 'XPC_SERVICE_NAME': '0', 03:49:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:49:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:49:48 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-rB1Pam-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:49:48 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:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:49:48 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-OfRxR9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:49:48 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:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:49:48 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-FAhehg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:49:48 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:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:49:48 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-2eU56V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:49:48 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:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:49:48 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-Ka2h5_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:49:48 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:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:49:48 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-dxUuk4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:49:48 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:49:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:49:49 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-hAtBXp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:49:49 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:49:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:49:49 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-Ah5BFw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:49:49 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:49:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:49:49 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-hGVcRD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:49:49 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:49:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:49:49 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-1wiWdg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:49:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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:49:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:49:49 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-kTGnqk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:49:49 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:49:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:49:49 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-W5J5zQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:49:49 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:49:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:49:49 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-_2AChD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:49:49 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:49:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:49:49 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-hFf3Hi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:49:49 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:49:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:49:49 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-EV61X0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:49:49 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:49:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:49:49 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-PmmpIM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:49:50 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:49:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:49:50 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-EM5SQN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:49:50 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:49:50 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:49:50 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:49:50 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:49:50 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:49:50 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:49:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:49:50 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:49:50 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:49:50 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:49:50 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:49:50 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:49:50 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:49:52 INFO - Downloading blessings-1.6.tar.gz 03:49:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:49:52 INFO - Installing collected packages: blessings 03:49:52 INFO - Running setup.py install for blessings 03:49:52 INFO - Successfully installed blessings 03:49:52 INFO - Cleaning up... 03:49:52 INFO - Return code: 0 03:49:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:49:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:49:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:49:53 INFO - Reading from file tmpfile_stdout 03:49:53 INFO - Current package versions: 03:49:53 INFO - blessings == 1.6 03:49:53 INFO - blobuploader == 1.2.4 03:49:53 INFO - docopt == 0.6.1 03:49:53 INFO - manifestparser == 1.1 03:49:53 INFO - mozInstall == 1.12 03:49:53 INFO - mozcrash == 0.17 03:49:53 INFO - mozdebug == 0.1 03:49:53 INFO - mozdevice == 0.48 03:49:53 INFO - mozfile == 1.2 03:49:53 INFO - mozhttpd == 0.7 03:49:53 INFO - mozinfo == 0.9 03:49:53 INFO - mozleak == 0.1 03:49:53 INFO - mozlog == 3.1 03:49:53 INFO - moznetwork == 0.27 03:49:53 INFO - mozprocess == 0.22 03:49:53 INFO - mozprofile == 0.28 03:49:53 INFO - mozrunner == 6.11 03:49:53 INFO - mozscreenshot == 0.1 03:49:53 INFO - mozsystemmonitor == 0.0 03:49:53 INFO - moztest == 0.7 03:49:53 INFO - mozversion == 1.4 03:49:53 INFO - psutil == 3.1.1 03:49:53 INFO - requests == 1.2.3 03:49:53 INFO - wsgiref == 0.1.2 03:49:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:49:53 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:49:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:49:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:49:53 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:49:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:49:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:49:53 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 0x10eaa7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaae4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcf9cd8190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e94bd30>, '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 0x10e7afdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e942b20>, '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.troASljsGd/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.UnJSvazDkE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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:49:53 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:49:53 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:49:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.troASljsGd/Render', 03:49:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:49:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:49:53 INFO - 'HOME': '/Users/cltbld', 03:49:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:49:53 INFO - 'LOGNAME': 'cltbld', 03:49:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:49:53 INFO - 'MOZ_NO_REMOTE': '1', 03:49:53 INFO - 'NO_EM_RESTART': '1', 03:49:53 INFO - 'PAGER': '/bin/cat', 03:49:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:49:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:49:53 INFO - 'PWD': '/builds/slave/test', 03:49:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:49:53 INFO - 'SHELL': '/bin/bash', 03:49:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UnJSvazDkE/Listeners', 03:49:53 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 03:49:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:49:53 INFO - 'USER': 'cltbld', 03:49:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:49:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:49:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:49:53 INFO - 'XPC_FLAGS': '0x0', 03:49:53 INFO - 'XPC_SERVICE_NAME': '0', 03:49:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:49:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:49:53 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:49:53 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:49:53 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:49:53 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:49:53 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:49:53 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:49:53 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:49:53 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:49:53 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:49:53 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:49:53 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:49:53 INFO - Cleaning up... 03:49:53 INFO - Return code: 0 03:49:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:49:53 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:49:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:49:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:49:53 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:49:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:49:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:49:53 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 0x10eaa7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaae4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcf9cd8190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e94bd30>, '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 0x10e7afdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e942b20>, '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.troASljsGd/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.UnJSvazDkE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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:49:53 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:49:53 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:49:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.troASljsGd/Render', 03:49:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:49:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:49:53 INFO - 'HOME': '/Users/cltbld', 03:49:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:49:53 INFO - 'LOGNAME': 'cltbld', 03:49:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:49:53 INFO - 'MOZ_NO_REMOTE': '1', 03:49:53 INFO - 'NO_EM_RESTART': '1', 03:49:53 INFO - 'PAGER': '/bin/cat', 03:49:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:49:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:49:53 INFO - 'PWD': '/builds/slave/test', 03:49:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:49:53 INFO - 'SHELL': '/bin/bash', 03:49:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UnJSvazDkE/Listeners', 03:49:53 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 03:49:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:49:53 INFO - 'USER': 'cltbld', 03:49:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:49:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:49:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:49:53 INFO - 'XPC_FLAGS': '0x0', 03:49:53 INFO - 'XPC_SERVICE_NAME': '0', 03:49:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:49:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:49:53 INFO - Downloading/unpacking jsonschema 03:49:53 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:49:53 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:49:53 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:49:53 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:49:53 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:49:53 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:49:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:49:56 INFO - Downloading/unpacking functools32 (from jsonschema) 03:49:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:49:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:49:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:49:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:49:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:49:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:49:56 INFO - Downloading functools32-3.2.3-2.tar.gz 03:49:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:49:56 INFO - warning: no files found matching '*.txt' 03:49:56 INFO - no previously-included directories found matching 'build' 03:49:56 INFO - no previously-included directories found matching 'dist' 03:49:56 INFO - no previously-included directories found matching '.git*' 03:49:56 INFO - Installing collected packages: jsonschema, functools32 03:49:56 INFO - Running setup.py install for functools32 03:49:56 INFO - warning: no files found matching '*.txt' 03:49:56 INFO - no previously-included directories found matching 'build' 03:49:56 INFO - no previously-included directories found matching 'dist' 03:49:56 INFO - no previously-included directories found matching '.git*' 03:49:56 INFO - Successfully installed jsonschema functools32 03:49:56 INFO - Cleaning up... 03:49:56 INFO - Return code: 0 03:49:56 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:49:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:49:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:49:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:49:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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:49:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:49:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:49:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10eaa7a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eaae4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffcf9cd8190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e94bd30>, '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 0x10e7afdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e942b20>, '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.troASljsGd/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.UnJSvazDkE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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:49:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 03:49:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 03:49:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.troASljsGd/Render', 03:49:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:49:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:49:56 INFO - 'HOME': '/Users/cltbld', 03:49:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:49:56 INFO - 'LOGNAME': 'cltbld', 03:49:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:49:56 INFO - 'MOZ_NO_REMOTE': '1', 03:49:56 INFO - 'NO_EM_RESTART': '1', 03:49:56 INFO - 'PAGER': '/bin/cat', 03:49:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:49:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:49:56 INFO - 'PWD': '/builds/slave/test', 03:49:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:49:56 INFO - 'SHELL': '/bin/bash', 03:49:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UnJSvazDkE/Listeners', 03:49:56 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 03:49:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:49:56 INFO - 'USER': 'cltbld', 03:49:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:49:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:49:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:49:56 INFO - 'XPC_FLAGS': '0x0', 03:49:56 INFO - 'XPC_SERVICE_NAME': '0', 03:49:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:49:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:49:57 INFO - Downloading/unpacking flake8 03:49:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:49:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:49:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:49:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:49:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:49:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:49:59 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:49:59 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:49:59 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:49:59 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:49:59 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:49:59 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:49:59 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:49:59 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:49:59 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:49:59 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:49:59 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:49:59 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:49:59 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:49:59 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:49:59 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:49:59 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:50:00 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:50: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:50: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:50: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:50: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:50: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:50: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:50:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:50:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:50:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:50:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:50:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:50:00 INFO - no previously-included directories found matching 'docs/_build' 03:50:00 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:50:00 INFO - Running setup.py install for pep8 03:50:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:50:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:50:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:50:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:50:01 INFO - no previously-included directories found matching 'docs/_build' 03:50:01 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:50:01 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:50:01 INFO - Cleaning up... 03:50:01 INFO - Return code: 0 03:50:01 INFO - Running post-action listener: _resource_record_post_action 03:50:01 INFO - Running post-action listener: _start_resource_monitoring 03:50:01 INFO - Starting resource monitoring. 03:50:01 INFO - ##### 03:50:01 INFO - ##### Running install step. 03:50:01 INFO - ##### 03:50:01 INFO - Running pre-action listener: _resource_record_pre_action 03:50:01 INFO - Running main action method: install 03:50:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:50:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:50:01 INFO - Reading from file tmpfile_stdout 03:50:01 INFO - Detecting whether we're running mozinstall >=1.0... 03:50:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:50:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:50:01 INFO - Reading from file tmpfile_stdout 03:50:01 INFO - Output received: 03:50:01 INFO - Usage: mozinstall [options] installer 03:50:01 INFO - Options: 03:50:01 INFO - -h, --help show this help message and exit 03:50:01 INFO - -d DEST, --destination=DEST 03:50:01 INFO - Directory to install application into. [default: 03:50:01 INFO - "/builds/slave/test"] 03:50:01 INFO - --app=APP Application being installed. [default: firefox] 03:50:01 INFO - mkdir: /builds/slave/test/build/application 03:50:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:50:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 03:50:34 INFO - Reading from file tmpfile_stdout 03:50:34 INFO - Output received: 03:50:34 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 03:50:34 INFO - Running post-action listener: _resource_record_post_action 03:50:34 INFO - ##### 03:50:34 INFO - ##### Running run-tests step. 03:50:34 INFO - ##### 03:50:34 INFO - Running pre-action listener: _resource_record_pre_action 03:50:34 INFO - Running main action method: run_tests 03:50:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:50:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:50:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:50:34 INFO - 2016-04-27 03:50:34.461 screenresolution[1639:7625] starting screenresolution argv=screenresolution get 03:50:34 INFO - 2016-04-27 03:50:34.463 screenresolution[1639:7625] Display 0: 1600x1200x32@60 03:50:34 INFO - 2016-04-27 03:50:34.469 screenresolution[1640:7628] starting screenresolution argv=screenresolution list 03:50:34 INFO - Available Modes on Display 0 03:50:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:50:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:50:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:50:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:50:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:50:34 INFO - Intel Iris: 03:50:34 INFO - Chipset Model: Intel Iris 03:50:34 INFO - Type: GPU 03:50:34 INFO - Bus: Built-In 03:50:34 INFO - VRAM (Dynamic, Max): 1536 MB 03:50:34 INFO - Vendor: Intel (0x8086) 03:50:34 INFO - Device ID: 0x0a2e 03:50:34 INFO - Revision ID: 0x0009 03:50:34 INFO - Displays: 03:50:34 INFO - Display: 03:50:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:50:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:50:34 INFO - Display Serial Number: 1600x1200 60 03:50:34 INFO - Main Display: Yes 03:50:34 INFO - Mirror: Off 03:50:34 INFO - Online: Yes 03:50:34 INFO - Rotation: Supported 03:50:34 INFO - Return code: 0 03:50:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:50:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:50:34 INFO - Python 2.7.3 03:50:34 INFO - Return code: 0 03:50:34 INFO - grabbing minidump binary from tooltool 03:50:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:50:34 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e94bd30>, '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 0x10e7afdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e942b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:50:34 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:50:34 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:50:34 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:50:34 INFO - Return code: 0 03:50:34 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:50:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:50:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:50:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:50:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:50:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:50:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:50:35 INFO - Return code: 0 03:50:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0116', '--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:50:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0116 --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:50:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.troASljsGd/Render', 03:50:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:50:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:50:35 INFO - 'HOME': '/Users/cltbld', 03:50:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:50:35 INFO - 'LOGNAME': 'cltbld', 03:50:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:50:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:50:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:50:35 INFO - 'MOZ_NO_REMOTE': '1', 03:50:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:50:35 INFO - 'NO_EM_RESTART': '1', 03:50:35 INFO - 'PAGER': '/bin/cat', 03:50:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:50:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:50:35 INFO - 'PWD': '/builds/slave/test', 03:50:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:50:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:50:35 INFO - 'SHELL': '/bin/bash', 03:50:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UnJSvazDkE/Listeners', 03:50:35 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 03:50:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:50:35 INFO - 'USER': 'cltbld', 03:50:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:50:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:50:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:50:35 INFO - 'XPC_FLAGS': '0x0', 03:50:35 INFO - 'XPC_SERVICE_NAME': '0', 03:50:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:50:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0116', '--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:50:36 INFO - mozversion application_buildid: 20160425042155 03:50:36 INFO - mozversion application_changeset: f328ac32af6a3d0ce1593c41fd18edf7216a1652 03:50:36 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:50:36 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:50:36 INFO - mozversion application_name: Firefox 03:50:36 INFO - mozversion application_remotingname: firefox-dev 03:50:36 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:50:36 INFO - mozversion application_vendor: Mozilla 03:50:36 INFO - mozversion application_version: 47.0a2 03:50:36 INFO - mozversion platform_buildid: 20160425042155 03:50:36 INFO - mozversion platform_changeset: f328ac32af6a3d0ce1593c41fd18edf7216a1652 03:50:36 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:50:36 INFO - mozversion platform_version: 47.0a2 03:50:36 INFO - using testdate: 1461754236 03:50:36 INFO - actual date: 1461754236 03:50:36 INFO - starting webserver on 'localhost:49284' 03:50:36 INFO - SUITE-START | Running 2 tests 03:50:36 INFO - TEST-START | dromaeo_css 03:50:36 INFO - operating with platform_type : mac_ 03:50:36 INFO - Initialising browser for dromaeo_css test... 03:50:36 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/tmpBmziBi/profile http://localhost:49284/getInfo.html) 03:50:38 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 03:50:38 INFO - PROCESS | 1658 | colorDepth:24 03:50:38 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 03:50:38 INFO - PROCESS | 1658 | __metrics 03:50:38 INFO - PROCESS | 1658 | 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:50:38 INFO - PROCESS | 1658 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:50:39 INFO - TEST-INFO | 1658: exit 0 03:50:39 INFO - Browser initialized. 03:50:39 INFO - Running cycle 1/1 for dromaeo_css test... 03:50:39 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/tmpBmziBi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:50:51 INFO - PROCESS | 1660 | RSS: Main: 207675392 03:50:51 INFO - PROCESS | 1660 | 03:51:42 INFO - PROCESS | 1660 | 1461754302250 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:53:52 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49284/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49284/tests/dromaeo/cssquery-ext.html) 03:53:53 INFO - PROCESS | 1660 | RSS: Main: 310657024 03:53:53 INFO - PROCESS | 1660 | 03:56:55 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49284/tests/dromaeo/cssquery-ext.html (next: http://localhost:49284/tests/dromaeo/cssquery-jquery.html) 03:56:56 INFO - PROCESS | 1660 | RSS: Main: 340467712 03:56:56 INFO - PROCESS | 1660 | 03:59:58 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49284/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49284/tests/dromaeo/cssquery-mootools.html) 03:59:58 INFO - PROCESS | 1660 | RSS: Main: 349827072 03:59:58 INFO - PROCESS | 1660 | 04:03:00 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49284/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49284/tests/dromaeo/cssquery-prototype.html) 04:03:01 INFO - PROCESS | 1660 | RSS: Main: 305659904 04:03:01 INFO - PROCESS | 1660 | 04:06:04 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49284/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49284/tests/dromaeo/cssquery-yui.html) 04:06:05 INFO - PROCESS | 1660 | RSS: Main: 307568640 04:06:05 INFO - PROCESS | 1660 | 04:09:07 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49284/tests/dromaeo/cssquery-yui.html (next: http://localhost:49284/tests/dromaeo/cssquery-dojo.html) 04:09:07 INFO - PROCESS | 1660 | RSS: Main: 356519936 04:09:07 INFO - PROCESS | 1660 | 04:09:07 INFO - PROCESS | 1660 | __start_tp_report 04:09:07 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 04:09:07 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 04:09:07 INFO - PROCESS | 1660 | |i|pagename|runs| 04:09:07 INFO - PROCESS | 1660 | |0;dojo.html;3335.266241426886;3442.414789485789;3457.3431048100874;3465.740069494788;3505.7195424366055;5635.746391412386;5640.830775076749;5641.802536911209;5656.434356564347;5666.32004159501;5624.100143976964;5660.688662123582;5675.404082571333;5678.971605141968;5694.373618901917;5025.7235852028125;5058.595312375001;5123.5388815006645;5128.307678463407;5162.22566615007;4987.925181122286;5200.401953775307;5212.0097181535575;5216.530512253897;5222.869428264305;5116.718580478072;5131.999260144274;5160.458151894053;5166.509181627739;5201.2458193561915;5195.740212989354;5213.843584692465;5222.399424066243;5231.503007214346;5263.0526505229045;4945.554900059012;5103.872403189913;5113.642045056848;5113.923291150636;5149.356330458692;5518.475744804237;5581.5255703265275;5591.077472217079;5610.915836262459;5626.212330273765;6031.698415079272;6215.098810672473;6237.844053898643;6238.500919926395;6239.06414037898;6143.354947730469;6162.7534898604;6188.164597779287;6190.380961903818;6300.432961033529;5743.971280143614;5772.538196944234;5772.682502462321;5777.075667893117;5785.797497087582;6272.9928317198965;6284.780032698834;6322.430981211713;6327.209098862642;6328.6835658216905;5923.763049477972;5953;5987.042073268343;6039.053342976186;6056.545759068087;6207.852839072648;6235.657038862906;6242.313345531988;6246.406591373812;6246.562740608205;6103.176071230416;6124.724387402578;6159.199304090529;6179.938200618026;6198.256209254918;6010.068439391901;6091.02543593023;6113.354685976905;6193.102000209944;6196.969015154986;4851.369321988119;4859.781309841065;4873.366462359942;4875.902481950412;4894.290327902434;4376.343548467756;4385;4401.933970990438;4413.293872980308;4430.335449682509;5508.944910550923;5566.860828479321;5604.943950560524;5631.2960879890015;5666.660000399989;6265.310815810257;6271.905921411182;6272.6550039747335;6297.937020629826;6302.527310451735;5587.8603034923635;5638.661680299195;5658.462446067617;5660.377358490563;5696.572757043238;5145.151050076695;5313.44208858072;5314.7608357624;5347.117725575315;5380.704061276589;6252.499800015988;6287.088372186007;6289.150964619809;6289.245290565162;6303.558750887486;6255.96872015637;6281.246250449975;6293.748250069946;6297.2128483939505;6306.936930630818;6219.253689557282;6232.594881332699;6272.40412160844;6295.905561416491;6305.464035557029;6146.078088286793;6163.075538669056;6168.9691551541955;6178.752849885954;6196.938030619816;5054.862655902392;6004.939950600613;6287.497000239879;6294.905576416266;6312.210973628296;5969.820905372846;6050.6369617823075;6070.2108725865355;6084.78703245402;6101;6023.548233882389;6052.848678783065;6060.393960603904;6065.029595264737;6069.605475644069;5779.133130030529;5881.823545293648;5886.028805247258;5900.616459930091;5908.350081490947;5632.464915832989;6042.123892035718;6059.878802424015;6082.300535438481;6108.053251745987;5843.2403787507355;5873.471387575139;5874.706264686663;5882.882342353043;5883.235179426647 04:09:07 INFO - PROCESS | 1660 | |1;ext.html;504.2159442067588;594.8875602623095;602.8395338972476;609.9085137229415;640.8526039011027;31777;32406.351872962558;32415.86544470944;32425.675743242562;32448.1887952801;33485.82799602015;42443.57556424435;42702.35946460805;42849.57150428495;42871.142577148436;9559.904400956006;9728.124468797807;9733.902660973406;9742.318037737326;9752.024797520227;9606.519674016306;9725.076117768836;9736.026397360278;9765.12113909748;9768.560414781316;9706.223502119814;9787.119159275664;9787.706368808947;9798.657047003357;9819.558119884587;9671.323007389485;9883.505824708807;9885.802283954317;9892.95053524735;9897.604095836194;8275.758636204557;9728.221742260603;9853.014698530162;9858.950705246429;9859.753506162331;40994;43344.783276083734;43363.34954975678;43391;43404.56595434037;3215.5980502437196;3517.7009954153928;3540.4689296605457;3644.69020133289;3849.9230015399817;28705.56941645877;29517.70482295171;30049.248768780955;30195.849020754977;30441.543376849364;2775.514285000137;2793.52510073288;2828.420173864353;2839.5172820620337;2865.6274684291116;416539;419458.90270548756;419534;420004;420157;47695.52304476911;48841.75579122153;69004.61990760156;69601.95597066048;69726;46812.297815532795;46921;47089.52910470851;47093.29360059602;47109;16479.92880462766;16644.91808032474;16649.16754162287;16655.91672041632;16663.416975387318;13264.734705306033;13362.665933351549;13368.064235503422;13368.33158342079;13371.866281337063;14998.850011499746;15023.399064037316;15044.699106017819;15061.171635559931;15158.242087895562;6416.518761092843;6511.511636627272;6554.082428460019;6555.082288479617;6576.539642225094;980.2305190425624;1042.864427624404;1097.4896673047072;1135.4947048563474;1154.584349634131;29527.704722952923;30350.696493034786;30441.39117217643;30760.846195768878;31015.069547913597;1183.7277426192024;1213.6662417835166;1253.5988483685317;1266.8226448297246;1274.1781550899618;8882.86675699865;16108.355665773472;16268;16497.587560311087;17002.404915827887;520107;588272;732819;733524;734579;64956.025659615145;65158.674206629614;65670.0149497758;65791.67104164448;65956.01065984015;1422.3954819201797;1472.0578829549097;1489.5307977986683;1630.8287629798938;1668.8414600612923;28433.715662843933;30829.075127746204;33357.16607084745;34137.65862341444;34227.14432139117;58782.4121758777;59244;59369.406305938115;59464.10803838033;59482.107768382644;406377;419414;419886;420395;420713.8964305159;30409.783608655405;35165.296694066485;35380.82309588538;35600.821995889855;36152;47718.04563908633;47985.5201447981;48072.79818004578;48095;49529;38118.42822357611;39054.02364940899;39055.828325150294;39260.41109383304;40618.9845253871;37495.812520938314;37867;38107.23785524219;38716.6128338713;38875.416868746426;39423;40583.98540036404;40862.38706419346;42066.57933420627;42098.36852447277;31604;34324.74882201909;34621.826890865384;34648.480272796434;34863.77976770801;25706.357341065876;25903.481930361664;26631.733682662925;26847.4494922982;27092.32269193286 04:09:07 INFO - PROCESS | 1660 | |2;jquery.html;377.98299076541554;382.29085047237373;386.58442174662247;389.9337112690842;394.1899396739699;16334.509964701077;16630;19756.617036807413;20623.2781852635;21095.789042109573;16571.337146514154;20740.066696998634;20888.791112088875;20967;21013.054412551435;5795.971020144893;5855.941440585582;5906.409359064102;5910.202122713443;5913.615614985034;5600.103983362663;5759.9136012959625;5831.271091113611;5865.76536938522;5870.88258234835;5837.766489340422;5851.6196447231005;5860.912086318709;5864.061750119981;5867.88264234715;5033.924491132636;5497.255368140303;5823.039198532237;5828.854278643025;5830.82507524775;5534.169874518814;5546.667199968015;5575.665460072409;5578.219049333096;5598.188262701926;13843.377048032862;23736.881315593484;23809.166679166145;24581.262562123164;24659.87670061656;2096.423483542038;2215.6898034275214;2918.4017276458276;3115.8286294253808;3156.416063028324;15158.772618410732;15458.304376303093;15483;15838.812089093364;15989.440369587124;1817.3639226270832;1867.9346222882134;1890.546268895469;1933.3426634944187;1947.250308631173;109749.90250097477;115884;118305.81694183034;119878.40060799728;126166;19270.518237044187;19393.127309271116;19445.708314375297;19493.978433342854;19535.804641953684;29839.701602983692;29853;30417.391652167276;30491.085267442013;30560.84719576388;9179.495127768037;10678.73303167427;10940.890591093987;11052.834207486894;11078.279911805706;9006.729798106067;9027.954860225656;9065.637374504946;9112.316576256808;9583.952080239555;7696.807579810549;8330.583470826434;8611.741647750578;8627.396082274181;8719.389642725075;2952.867120979561;2961.940761184764;3074.5388191771412;3159.921001974969;3478.339115568034;780.6994307191773;917.1104029091904;970.9223262139012;974.181687382602;1045.435464848973;19755.308564200182;20663.276785312442;20727.963601819847;20737.377878663665;20767.8961605191;1025.477006726575;1026.2508368890751;1026.830572955454;1074.2319241742036;1125.099920063962;10697;11114.166437517219;11121.165007773627;11146.665600032013;11169.329840209613;606396;614987;618599.9070004711;626826;627450;112430;114089;114119;119314;123613;1038.3354653022072;1063.8510608514814;1075.3118004477142;1075.8386242063752;1077.1329080090602;17638.823611763717;17683.313288186026;17744.3789467368;18047.458576242734;18264.634707306046;49204.507954919995;49720.751396242784;51685.483145168066;52004;52877.47122528879;45582.31626525671;46709;70655.29344706488;81453.18546814455;81471.18528814873;7922.920770792219;8082.87875681877;8161.510309381456;8206.753797386087;8292.792680183044;12602;12990.090693651353;13116.278604676834;13162.276074815594;13310.86689133135;11506.021988131102;11517.366544840113;11579.015783658484;11613.419329033346;11756.764864702829;11460.312381257152;11515.654530364103;11652.766944660889;11679.240849344766;11872.703182420508;11640.708982275512;11643.708907277385;11790.528378864748;11911.285322880654;11939.880601193878;8252.711155109542;8260.173982601691;8347.290480309242;8371.539565323723;8456.154384561494;8528.701495447629;11010.889891100986;11656.475458604553;11706.88293117058;11870.52517899309 04:09:07 INFO - PROCESS | 1660 | |3;mootools.html;1519.528946026731;1622.6998005369007;1661.3105561192106;1967.9409617711474;2036.857419980602;8360.3729720271;8866.689665861697;9054.185123338899;9060.048694887024;9078.818423631523;8797.912020879789;9056.81886362274;9072.682456114037;9075.183233508982;9080;2156.3423155937435;2217.756046834847;2516.6854143232094;2566.627838963354;2577.226831950417;2583.1346498922885;2590.0675756727564;2595.364135786735;2596.1692258477296;2599.8960041598407;2136.39112852837;2500.3999040230306;2576.1112416216374;2580.213035024317;2581.2901452100637;2520.243926821955;2560.6287088372173;2580.019592554836;2585.896564137432;2586.9870650646626;2536.353229926375;2561.9880147341883;2575.562154433751;2580.4323048929205;2585.0952166741677;7782.805429864242;9120.224780893566;9189.264858811279;9210.631574737003;9225.815483690289;5617.634853734535;5725.484706376405;5774.393688662714;5782.56630752691;5788.450097240755;3002.4445477586496;3618.3486972344967;3620.5112309838096;3648.762830416041;3654.3787556115526;1058.7882423515327;1063.9308444951052;1070.0690399352613;1077.3374374759544;1107.8172101603225;12447.75104497914;12451.315177665225;12463.501459941592;12467.999999999909;12471;4970.577500912463;4989.80040798364;5070.3155074064525;5084.161113416319;5089.541941225308;10558.78573964004;10584.417857017785;10593.470326483644;10600.734981625521;10607.893921060844;5727.140928860621;5744.684042377626;5751.051076572402;5756.395578464285;5759.913601295984;4599.471060827981;4762.64280178988;4988.501149884983;5108.66793658411;5166.070107380708;3975.6421922027157;4061.8375264989068;4075.89810254746;4092.4066010428323;4135.669146468275;1344.153183494412;1355.8440779310308;1355.857634948336;1365.2968721108637;1366.1188533395905;1308.5616318533398;1338.2773302416585;1392.4082265037318;1460.4158336665207;1462.0642788615294;8393.286570641563;8573.442726222776;8585.914140858637;8592.183742544448;8594.097619749962;533.9546138578264;539.7409243563034;550.4742970463197;552.701541167765;552.7982286465492;5342.412334643186;6333.303336632966;6334.461570766536;6369;6384.904226436607;10640.201984851166;11013.2841365311;11021.338719676685;11121.443927803577;11141.665750027512;10587.629432969808;10899.564017439372;10952.561897524169;10968.396738179317;11098.889011109786;8592.527410992581;8594.484330940164;8612.65549378018;8684.044755076937;8684.8697269541;8113.1886811320755;8142.592870356577;8167.1424500428975;8477.98264208299;8490.108538603357;9502.572384242863;9880.35777674449;9947.154491868272;10010.549525271235;10034.297599167989;9862.309638325249;10175.084242418217;10345.017223363768;10345.120664743281;10369;7781.572013539309;7782.727604533814;7783.104942931409;7849.254320839511;7859.567723775247;8501.574921253788;8514.91485085166;8581.65673373058;8596.742097736827;8603.741887743377;7483.32650061497;7589.734359297398;7659.387249019954;7749.573773442515;7842.882356764767;7335.339819416278;7427.4800763946005;7485.288897554724;7562.848743025219;7600.239976002355;7940.205979402013;8004.239597238253;8020.398470114881;8020.919790802017;8045.718399856211;7176;7206.135263768381;7252.528585641917;7295.343419092307;7316.4146868249345;7412.369948554433;7427.665755040928;7464.328210461084;7467.962660186664;7474.2525747427 04:09:07 INFO - PROCESS | 1660 | |4;prototype.html;340.51476645779763;346.5494856686307;347.1183194685499;350.14913759564257;353.3322021379593;6586.901196482057;6626.635535045577;6657.034729964153;6702.832429189272;6704.631245281507;6579.407853293203;6611.933880661192;6622.304658010899;6628.469722422206;6645.833854153649;4164.896302479845;4723.834665786698;4747.810087596492;4887.364642596475;4910.558049775519;4849.636277279218;4860.5139486051285;4862.951370486286;4880.267959806022;4893.510648935114;4786.186348320776;4819.373481447407;4823.951760482403;4833.661643684926;4835.347228124211;3726.441033844918;4736;4736;4775.761211939423;4829.51704829516;4086.0806318578084;4167.166566686645;4176.7076304658685;4177.707560470768;4186.183694179636;5258.316418865523;6180.6291622502795;6311.747530098791;6340.302566717657;6363.236411630634;5786.855328616776;5788.479036886658;5790.507806836382;5794.333651630074;5805.477507024388;7003.15962084548;7016.543924644881;7019.017337572744;7029.859402811915;7039.296070392971;4559.229943986057;4752.59602933751;4755.785989630475;4765.28520721891;4767.713937163781;7353.1911489736085;7369.299916507923;7381.4094872410615;7396.4452666050265;7406.555606663617;4833.516648335139;4833.540813622701;4835.347228124158;4843.660943733905;4849.733264670477;4913.385826771653;4916.3362946001835;4925.310456536088;4932.964077543756;4935.407751069823;5018.24726291052;5480.520259529904;5779;5796.130580412888;5804.187413762077;6734.764283250062;7001.089858318386;7037.824054398681;7064.858702825914;7098.077249957472;1332.7134666046652;1633.020187887282;1645.967080658404;1646.5965838369675;1647;1036.3522798251092;1046.4349251404155;1060.7189094889861;1061.994690026545;1065.2383545764815;5724.198612194296;6243.282022567463;6340.080688300171;6546.279909209983;6703.899441508382;7393.297636724503;7398.519096258726;7401.48189626721;7407.777766667009;7433.739819106306;6625.701843417058;6628.635425051572;6629.303923088007;6633.70148343316;6640.468762498988;3866.1107945172766;3912.56961734209;3913.491246138041;3915.2169566086327;3922.0783115967724;7201.667691477093;7378.852422951511;7660.502067365604;7681.846363072707;7727.343175830005;6714.966425167843;6786.59280443175;7030.578165309996;7590.734324298623;7609.1085387553485;7661.885071723928;7676.19399963007;7690.999999999888;7697.4226932980255;7700.614969251627;7356.926430735838;7357.668904899399;7366.189719130998;7368.631568421664;7370.115586129698;6930.61881596517;7174.569525828467;7224.096987876515;7225.494215404871;7233.132024157135;7658.885116723142;7683.538987660758;7688.269614386624;7692.461527693008;7698.499597526143;4698.577128058491;4711.434627844681;4713.434387873477;4716;4722.858314250578;7356.77929662111;7360.33756961876;7376.96311518439;7392.186859445565;7401.148867880047;6752.628605426748;7105.928940710527;7112.50212485121;7117.893231601426;7118.466115041496;7023.403010744144;7074.929250707427;7080.539764915264;7088.0785497884945;7094.716211351488;6927.515073944977;6940.09778728762;6954.1404520491615;6955;6960.408365288805;6780.762673306411;7070.71717131309;7082.220955694766;7095.3968912643;7109.040279562193;6654.866902662016;6850.4862135341045;6931;6942.826429339307;6950.582965021913 04:09:07 INFO - PROCESS | 1660 | |5;yui.html;704.4012589299097;717.9282071792821;733.765195137556;770.4606775257314;775.3990657240638;15203;15248.466303679374;15419.614509637264;15442.99620524665;15458.536243912673;14367.209803460832;14447.422103115863;14457.927710361433;14459.132452052858;14555;783.6121120045572;798.9840203195962;821.1008945204991;951.6907005223295;993.6869885985891;969.5830792759111;976.077606661705;977.1449981266392;977.3940157102571;977.5747549815817;1041.3751748950606;1045.2369770067855;1046.539522610049;1046.848207009983;1048.035807057505;831.1314676163423;1026.5688410867454;1056.4136904018267;1056.4136904018267;1056.4981633723996;780.7657702689171;784.5292824305428;785.1089013969129;785.2422412372031;785.7603430953557;15603.765943510856;15674.294656740474;15685.294161762748;15699.843001570067;15724.764128538081;12973.96208303343;13257.933710331386;13342.599722008355;13352.666183345396;13507.86492135076;10042.297039207258;10219.591216351337;10250.231232657581;10541.31481453703;10620.415877126756;3593.245418462126;3594.4967704521387;3646.58064322601;3708.684761795251;3879.243547508237;78646.6067669658;80216;82110.58944705297;82633.58683206546;82899.17100828975;9915.011354958895;10104.191664666809;10207.693769186937;10288.948555257175;10290.948545257226;23623.055077796696;23643.645345319834;23689.407764805674;23708.762912371;23733.16933907305;7592.050993625797;7654.6221680098115;7762.611869406507;7855.057393112749;7890.250426209501;4126.91746165081;4135.400366946776;4200.4539409876525;4265.936010959838;4294.527601963781;3747.569029561581;3777.395616701315;3798.164403831153;3801.7148713846573;3812.790296533717;580.6167929166729;601.3895895665903;679.239252037711;696.1437431958719;748.8839229919371;505.2648396582963;594.3313772006493;604.4469310580857;610.2128254551667;618.6937465954292;8155.551444670481;8377.371697122619;8430.352860227;8481.618327175293;8525.104863989192;524.9921251181236;597.5846786483439;597.6175247841385;615.4553220399978;625.2996643758928;3872.593377695358;3910.648041676206;3911.1786524829536;4021.5375231848125;4054.8378064877666;91884.54057729669;91961;93006;93286.53356733173;93337.53331233436;80539.59730201428;80820.59589702013;81382;82239.5888020556;83291;72056;72438.63780680958;73407;74192.88710669344;74600;7762.3790096793255;7841.882371764542;7987.40094492927;8120.959395202986;8330.04204516464;51715;52540.42378728644;52540.73729631327;52921.470785291654;53960.65098372572;66062.66968665126;68413.31586684269;70441;70575.94136088065;73378;10329.586816527255;10664.306820056672;10740.785184296426;10776.568937242422;10914.399708016135;10751.784964300827;10955;10968.561257549609;11181.944090279496;11196.66410007701;10429.582816687247;10536.473176341306;10544.841827372442;10722.51748671296;10835.783284334428;10526.736831578966;10577.576896924036;10772.946135269274;10804.891951080703;10867.402292873652;10154.984501550081;10271.84592231132;10338.276320657482;10577.894221057692;10616.097631701392;9008.369414140947;9013.819723605622;9025.684101056431;9197.54012299369;9231.630734770803;8990.460572365679;9084.636614535344;9114.04302548223;9140.6800761973;9250.306227033134 04:09:07 INFO - PROCESS | 1660 | __end_tp_report 04:09:07 INFO - PROCESS | 1660 | __start_cc_report 04:09:07 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,939 04:09:07 INFO - PROCESS | 1660 | __end_cc_report 04:09:07 INFO - PROCESS | 1660 | __startTimestamp1461755347831__endTimestamp 04:09:07 INFO - PROCESS | 1660 | 04:09:07 INFO - PROCESS | 1660 | ------- Summary: start ------- 04:09:07 INFO - PROCESS | 1660 | Number of tests: 6 04:09:07 INFO - PROCESS | 1660 | 04:09:07 INFO - PROCESS | 1660 | [#0] dojo.html Cycles:165 Average:5696.31 Median:5882.88 stddev:634.19 (10.8%) stddev-sans-first:608.49 04:09:07 INFO - PROCESS | 1660 | Values: 3335.3 3442.4 3457.3 3465.7 3505.7 5635.7 5640.8 5641.8 5656.4 5666.3 5624.1 5660.7 5675.4 5679.0 5694.4 5025.7 5058.6 5123.5 5128.3 5162.2 4987.9 5200.4 5212.0 5216.5 5222.9 5116.7 5132.0 5160.5 5166.5 5201.2 5195.7 5213.8 5222.4 5231.5 5263.1 4945.6 5103.9 5113.6 5113.9 5149.4 5518.5 5581.5 5591.1 5610.9 5626.2 6031.7 6215.1 6237.8 6238.5 6239.1 6143.4 6162.8 6188.2 6190.4 6300.4 5744.0 5772.5 5772.7 5777.1 5785.8 6273.0 6284.8 6322.4 6327.2 6328.7 5923.8 5953.0 5987.0 6039.1 6056.5 6207.9 6235.7 6242.3 6246.4 6246.6 6103.2 6124.7 6159.2 6179.9 6198.3 6010.1 6091.0 6113.4 6193.1 6197.0 4851.4 4859.8 4873.4 4875.9 4894.3 4376.3 4385.0 4401.9 4413.3 4430.3 5508.9 5566.9 5604.9 5631.3 5666.7 6265.3 6271.9 6272.7 6297.9 6302.5 5587.9 5638.7 5658.5 5660.4 5696.6 5145.2 5313.4 5314.8 5347.1 5380.7 6252.5 6287.1 6289.2 6289.2 6303.6 6256.0 6281.2 6293.7 6297.2 6306.9 6219.3 6232.6 6272.4 6295.9 6305.5 6146.1 6163.1 6169.0 6178.8 6196.9 5054.9 6004.9 6287.5 6294.9 6312.2 5969.8 6050.6 6070.2 6084.8 6101.0 6023.5 6052.8 6060.4 6065.0 6069.6 5779.1 5881.8 5886.0 5900.6 5908.4 5632.5 6042.1 6059.9 6082.3 6108.1 5843.2 5873.5 5874.7 5882.9 5883.2 04:09:07 INFO - PROCESS | 1660 | 04:09:07 INFO - PROCESS | 1660 | [#1] ext.html Cycles:180 Average:64643.57 Median:223497.99 stddev:137667.88 (61.6%) stddev-sans-first:137969.85 04:09:07 INFO - PROCESS | 1660 | Values: 504.2 594.9 602.8 609.9 640.9 31777.0 32406.4 32415.9 32425.7 32448.2 33485.8 42443.6 42702.4 42849.6 42871.1 9559.9 9728.1 9733.9 9742.3 9752.0 9606.5 9725.1 9736.0 9765.1 9768.6 9706.2 9787.1 9787.7 9798.7 9819.6 9671.3 9883.5 9885.8 9893.0 9897.6 8275.8 9728.2 9853.0 9859.0 9859.8 40994.0 43344.8 43363.3 43391.0 43404.6 3215.6 3517.7 3540.5 3644.7 3849.9 28705.6 29517.7 30049.2 30195.8 30441.5 2775.5 2793.5 2828.4 2839.5 2865.6 416539.0 419458.9 419534.0 420004.0 420157.0 47695.5 48841.8 69004.6 69602.0 69726.0 46812.3 46921.0 47089.5 47093.3 47109.0 16479.9 16644.9 16649.2 16655.9 16663.4 13264.7 13362.7 13368.1 13368.3 13371.9 14998.9 15023.4 15044.7 15061.2 15158.2 6416.5 6511.5 6554.1 6555.1 6576.5 980.2 1042.9 1097.5 1135.5 1154.6 29527.7 30350.7 30441.4 30760.8 31015.1 1183.7 1213.7 1253.6 1266.8 1274.2 8882.9 16108.4 16268.0 16497.6 17002.4 520107.0 588272.0 732819.0 733524.0 734579.0 64956.0 65158.7 65670.0 65791.7 65956.0 1422.4 1472.1 1489.5 1630.8 1668.8 28433.7 30829.1 33357.2 34137.7 34227.1 58782.4 59244.0 59369.4 59464.1 59482.1 406377.0 419414.0 419886.0 420395.0 420713.9 30409.8 35165.3 35380.8 35600.8 36152.0 47718.0 47985.5 48072.8 48095.0 49529.0 38118.4 39054.0 39055.8 39260.4 40619.0 37495.8 37867.0 38107.2 38716.6 38875.4 39423.0 40584.0 40862.4 42066.6 42098.4 31604.0 34324.7 34621.8 34648.5 34863.8 25706.4 25903.5 26631.7 26847.4 27092.3 04:09:07 INFO - PROCESS | 1660 | 04:09:07 INFO - PROCESS | 1660 | [#2] jquery.html Cycles:180 Average:35807.14 Median:11655.74 stddev:102558.42 (879.9%) stddev-sans-first:102811.62 04:09:07 INFO - PROCESS | 1660 | Values: 378.0 382.3 386.6 389.9 394.2 16334.5 16630.0 19756.6 20623.3 21095.8 16571.3 20740.1 20888.8 20967.0 21013.1 5796.0 5855.9 5906.4 5910.2 5913.6 5600.1 5759.9 5831.3 5865.8 5870.9 5837.8 5851.6 5860.9 5864.1 5867.9 5033.9 5497.3 5823.0 5828.9 5830.8 5534.2 5546.7 5575.7 5578.2 5598.2 13843.4 23736.9 23809.2 24581.3 24659.9 2096.4 2215.7 2918.4 3115.8 3156.4 15158.8 15458.3 15483.0 15838.8 15989.4 1817.4 1867.9 1890.5 1933.3 1947.3 109749.9 115884.0 118305.8 119878.4 126166.0 19270.5 19393.1 19445.7 19494.0 19535.8 29839.7 29853.0 30417.4 30491.1 30560.8 9179.5 10678.7 10940.9 11052.8 11078.3 9006.7 9028.0 9065.6 9112.3 9584.0 7696.8 8330.6 8611.7 8627.4 8719.4 2952.9 2961.9 3074.5 3159.9 3478.3 780.7 917.1 970.9 974.2 1045.4 19755.3 20663.3 20728.0 20737.4 20767.9 1025.5 1026.3 1026.8 1074.2 1125.1 10697.0 11114.2 11121.2 11146.7 11169.3 606396.0 614987.0 618599.9 626826.0 627450.0 112430.0 114089.0 114119.0 119314.0 123613.0 1038.3 1063.9 1075.3 1075.8 1077.1 17638.8 17683.3 17744.4 18047.5 18264.6 49204.5 49720.8 51685.5 52004.0 52877.5 45582.3 46709.0 70655.3 81453.2 81471.2 7922.9 8082.9 8161.5 8206.8 8292.8 12602.0 12990.1 13116.3 13162.3 13310.9 11506.0 11517.4 11579.0 11613.4 11756.8 11460.3 11515.7 11652.8 11679.2 11872.7 11640.7 11643.7 11790.5 11911.3 11939.9 8252.7 8260.2 8347.3 8371.5 8456.2 8528.7 11010.9 11656.5 11706.9 11870.5 04:09:07 INFO - PROCESS | 1660 | 04:09:07 INFO - PROCESS | 1660 | [#3] mootools.html Cycles:180 Average:6262.38 Median:2850.01 stddev:3331.86 (116.9%) stddev-sans-first:3322.13 04:09:07 INFO - PROCESS | 1660 | Values: 1519.5 1622.7 1661.3 1967.9 2036.9 8360.4 8866.7 9054.2 9060.0 9078.8 8797.9 9056.8 9072.7 9075.2 9080.0 2156.3 2217.8 2516.7 2566.6 2577.2 2583.1 2590.1 2595.4 2596.2 2599.9 2136.4 2500.4 2576.1 2580.2 2581.3 2520.2 2560.6 2580.0 2585.9 2587.0 2536.4 2562.0 2575.6 2580.4 2585.1 7782.8 9120.2 9189.3 9210.6 9225.8 5617.6 5725.5 5774.4 5782.6 5788.5 3002.4 3618.3 3620.5 3648.8 3654.4 1058.8 1063.9 1070.1 1077.3 1107.8 12447.8 12451.3 12463.5 12468.0 12471.0 4970.6 4989.8 5070.3 5084.2 5089.5 10558.8 10584.4 10593.5 10600.7 10607.9 5727.1 5744.7 5751.1 5756.4 5759.9 4599.5 4762.6 4988.5 5108.7 5166.1 3975.6 4061.8 4075.9 4092.4 4135.7 1344.2 1355.8 1355.9 1365.3 1366.1 1308.6 1338.3 1392.4 1460.4 1462.1 8393.3 8573.4 8585.9 8592.2 8594.1 534.0 539.7 550.5 552.7 552.8 5342.4 6333.3 6334.5 6369.0 6384.9 10640.2 11013.3 11021.3 11121.4 11141.7 10587.6 10899.6 10952.6 10968.4 11098.9 8592.5 8594.5 8612.7 8684.0 8684.9 8113.2 8142.6 8167.1 8478.0 8490.1 9502.6 9880.4 9947.2 10010.5 10034.3 9862.3 10175.1 10345.0 10345.1 10369.0 7781.6 7782.7 7783.1 7849.3 7859.6 8501.6 8514.9 8581.7 8596.7 8603.7 7483.3 7589.7 7659.4 7749.6 7842.9 7335.3 7427.5 7485.3 7562.8 7600.2 7940.2 8004.2 8020.4 8020.9 8045.7 7176.0 7206.1 7252.5 7295.3 7316.4 7412.4 7427.7 7464.3 7468.0 7474.3 04:09:07 INFO - PROCESS | 1660 | 04:09:07 INFO - PROCESS | 1660 | [#4] prototype.html Cycles:180 Average:5793.16 Median:6628.55 stddev:1847.41 (27.9%) stddev-sans-first:1806.69 04:09:07 INFO - PROCESS | 1660 | Values: 340.5 346.5 347.1 350.1 353.3 6586.9 6626.6 6657.0 6702.8 6704.6 6579.4 6611.9 6622.3 6628.5 6645.8 4164.9 4723.8 4747.8 4887.4 4910.6 4849.6 4860.5 4863.0 4880.3 4893.5 4786.2 4819.4 4824.0 4833.7 4835.3 3726.4 4736.0 4736.0 4775.8 4829.5 4086.1 4167.2 4176.7 4177.7 4186.2 5258.3 6180.6 6311.7 6340.3 6363.2 5786.9 5788.5 5790.5 5794.3 5805.5 7003.2 7016.5 7019.0 7029.9 7039.3 4559.2 4752.6 4755.8 4765.3 4767.7 7353.2 7369.3 7381.4 7396.4 7406.6 4833.5 4833.5 4835.3 4843.7 4849.7 4913.4 4916.3 4925.3 4933.0 4935.4 5018.2 5480.5 5779.0 5796.1 5804.2 6734.8 7001.1 7037.8 7064.9 7098.1 1332.7 1633.0 1646.0 1646.6 1647.0 1036.4 1046.4 1060.7 1062.0 1065.2 5724.2 6243.3 6340.1 6546.3 6703.9 7393.3 7398.5 7401.5 7407.8 7433.7 6625.7 6628.6 6629.3 6633.7 6640.5 3866.1 3912.6 3913.5 3915.2 3922.1 7201.7 7378.9 7660.5 7681.8 7727.3 6715.0 6786.6 7030.6 7590.7 7609.1 7661.9 7676.2 7691.0 7697.4 7700.6 7356.9 7357.7 7366.2 7368.6 7370.1 6930.6 7174.6 7224.1 7225.5 7233.1 7658.9 7683.5 7688.3 7692.5 7698.5 4698.6 4711.4 4713.4 4716.0 4722.9 7356.8 7360.3 7377.0 7392.2 7401.1 6752.6 7105.9 7112.5 7117.9 7118.5 7023.4 7074.9 7080.5 7088.1 7094.7 6927.5 6940.1 6954.1 6955.0 6960.4 6780.8 7070.7 7082.2 7095.4 7109.0 6654.9 6850.5 6931.0 6942.8 6950.6 04:09:07 INFO - PROCESS | 1660 | 04:09:07 INFO - PROCESS | 1660 | [#5] yui.html Cycles:180 Average:18697.78 Median:26532.71 stddev:26404.84 (99.5%) stddev-sans-first:26444.35 04:09:07 INFO - PROCESS | 1660 | Values: 704.4 717.9 733.8 770.5 775.4 15203.0 15248.5 15419.6 15443.0 15458.5 14367.2 14447.4 14457.9 14459.1 14555.0 783.6 799.0 821.1 951.7 993.7 969.6 976.1 977.1 977.4 977.6 1041.4 1045.2 1046.5 1046.8 1048.0 831.1 1026.6 1056.4 1056.4 1056.5 780.8 784.5 785.1 785.2 785.8 15603.8 15674.3 15685.3 15699.8 15724.8 12974.0 13257.9 13342.6 13352.7 13507.9 10042.3 10219.6 10250.2 10541.3 10620.4 3593.2 3594.5 3646.6 3708.7 3879.2 78646.6 80216.0 82110.6 82633.6 82899.2 9915.0 10104.2 10207.7 10288.9 10290.9 23623.1 23643.6 23689.4 23708.8 23733.2 7592.1 7654.6 7762.6 7855.1 7890.3 4126.9 4135.4 4200.5 4265.9 4294.5 3747.6 3777.4 3798.2 3801.7 3812.8 580.6 601.4 679.2 696.1 748.9 505.3 594.3 604.4 610.2 618.7 8155.6 8377.4 8430.4 8481.6 8525.1 525.0 597.6 597.6 615.5 625.3 3872.6 3910.6 3911.2 4021.5 4054.8 91884.5 91961.0 93006.0 93286.5 93337.5 80539.6 80820.6 81382.0 82239.6 83291.0 72056.0 72438.6 73407.0 74192.9 74600.0 7762.4 7841.9 7987.4 8121.0 8330.0 51715.0 52540.4 52540.7 52921.5 53960.7 66062.7 68413.3 70441.0 70575.9 73378.0 10329.6 10664.3 10740.8 10776.6 10914.4 10751.8 10955.0 10968.6 11181.9 11196.7 10429.6 10536.5 10544.8 10722.5 10835.8 10526.7 10577.6 10772.9 10804.9 10867.4 10155.0 10271.8 10338.3 10577.9 10616.1 9008.4 9013.8 9025.7 9197.5 9231.6 8990.5 9084.6 9114.0 9140.7 9250.3 04:09:07 INFO - PROCESS | 1660 | -------- Summary: end -------- 04:09:07 INFO - PROCESS | 1660 | 04:09:08 INFO - TEST-INFO | 1660: exit 0 04:09:08 INFO - TEST-OK | dromaeo_css | took 1112214ms 04:09:08 INFO - TEST-START | kraken 04:09:08 INFO - operating with platform_type : mac_ 04:09:08 INFO - Initialising browser for kraken test... 04:09:08 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/tmpxVcwqO/profile http://localhost:49284/getInfo.html) 04:09:10 INFO - PROCESS | 1674 | __metrics Screen width/height:1600/1200 04:09:10 INFO - PROCESS | 1674 | colorDepth:24 04:09:10 INFO - PROCESS | 1674 | Browser inner width/height: 1024/689 04:09:10 INFO - PROCESS | 1674 | __metrics 04:09:10 INFO - PROCESS | 1674 | 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" 04:09:10 INFO - PROCESS | 1674 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:09:11 INFO - TEST-INFO | 1674: exit 0 04:09:11 INFO - Browser initialized. 04:09:11 INFO - Running cycle 1/1 for kraken test... 04:09:11 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/tmpxVcwqO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:09:23 INFO - PROCESS | 1676 | RSS: Main: 207855616 04:09:23 INFO - PROCESS | 1676 | 04:09:25 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49284/tests/kraken/driver.html?testName=audio-beat-detection) 04:09:26 INFO - PROCESS | 1676 | RSS: Main: 263155712 04:09:26 INFO - PROCESS | 1676 | 04:09:28 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49284/tests/kraken/driver.html?testName=audio-dft) 04:09:29 INFO - PROCESS | 1676 | RSS: Main: 273645568 04:09:29 INFO - PROCESS | 1676 | 04:09:32 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49284/tests/kraken/driver.html?testName=audio-fft) 04:09:33 INFO - PROCESS | 1676 | RSS: Main: 264224768 04:09:33 INFO - PROCESS | 1676 | 04:09:35 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49284/tests/kraken/driver.html?testName=audio-oscillator) 04:09:35 INFO - PROCESS | 1676 | RSS: Main: 266076160 04:09:35 INFO - PROCESS | 1676 | 04:09:37 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49284/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:09:38 INFO - PROCESS | 1676 | RSS: Main: 266354688 04:09:38 INFO - PROCESS | 1676 | 04:09:41 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49284/tests/kraken/driver.html?testName=imaging-darkroom) 04:09:41 INFO - PROCESS | 1676 | RSS: Main: 266260480 04:09:41 INFO - PROCESS | 1676 | 04:09:44 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49284/tests/kraken/driver.html?testName=imaging-desaturate) 04:09:45 INFO - PROCESS | 1676 | RSS: Main: 266211328 04:09:45 INFO - PROCESS | 1676 | 04:09:47 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49284/tests/kraken/driver.html?testName=json-parse-financial) 04:09:48 INFO - PROCESS | 1676 | RSS: Main: 266534912 04:09:48 INFO - PROCESS | 1676 | 04:09:50 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49284/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:09:50 INFO - PROCESS | 1676 | RSS: Main: 300097536 04:09:50 INFO - PROCESS | 1676 | 04:09:52 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49284/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:09:53 INFO - PROCESS | 1676 | RSS: Main: 301015040 04:09:53 INFO - PROCESS | 1676 | 04:09:55 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49284/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:09:55 INFO - PROCESS | 1676 | RSS: Main: 306266112 04:09:55 INFO - PROCESS | 1676 | 04:09:58 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49284/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:09:58 INFO - PROCESS | 1676 | RSS: Main: 307912704 04:09:58 INFO - PROCESS | 1676 | 04:10:01 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49284/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:10:02 INFO - PROCESS | 1676 | RSS: Main: 307941376 04:10:02 INFO - PROCESS | 1676 | 04:10:03 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49284/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49284/tests/kraken/driver.html?testName=ai-astar) 04:10:04 INFO - PROCESS | 1676 | RSS: Main: 370847744 04:10:04 INFO - PROCESS | 1676 | 04:10:04 INFO - PROCESS | 1676 | __start_tp_report 04:10:04 INFO - PROCESS | 1676 | _x_x_mozilla_page_load 04:10:04 INFO - PROCESS | 1676 | _x_x_mozilla_page_load_details 04:10:04 INFO - PROCESS | 1676 | |i|pagename|runs| 04:10:04 INFO - PROCESS | 1676 | |0;ai-astar;112;109;109;109;111;112;119;111;109;111 04:10:04 INFO - PROCESS | 1676 | |1;audio-beat-detection;154;125;151;147;114;161;114;150;149;118 04:10:04 INFO - PROCESS | 1676 | |2;audio-dft;186;186;185;187;189;184;186;187;186;183 04:10:04 INFO - PROCESS | 1676 | |3;audio-fft;74;71;77;117;53;56;57;60;118;56 04:10:04 INFO - PROCESS | 1676 | |4;audio-oscillator;91;92;130;81;80;89;117;76;81;118 04:10:04 INFO - PROCESS | 1676 | |5;imaging-gaussian-blur;87;82;88;90;87;83;87;84;86;83 04:10:04 INFO - PROCESS | 1676 | |6;imaging-darkroom;92;92;92;93;93;93;92;94;93;92 04:10:04 INFO - PROCESS | 1676 | |7;imaging-desaturate;85;87;87;87;86;86;85;85;85;87 04:10:04 INFO - PROCESS | 1676 | |8;json-parse-financial;55;57;57;56;58;58;57;68;56;50 04:10:04 INFO - PROCESS | 1676 | |9;json-stringify-tinderbox;61;65;64;79;73;71;66;66;65;65 04:10:04 INFO - PROCESS | 1676 | |10;stanford-crypto-aes;81;81;80;80;80;94;105;89;80;83 04:10:04 INFO - PROCESS | 1676 | |11;stanford-crypto-ccm;121;128;127;117;115;118;125;116;133;127 04:10:04 INFO - PROCESS | 1676 | |12;stanford-crypto-pbkdf2;135;132;176;170;131;123;134;134;134;131 04:10:04 INFO - PROCESS | 1676 | |13;stanford-crypto-sha256-iterative;67;65;67;64;67;79;91;94;76;65 04:10:04 INFO - PROCESS | 1676 | __end_tp_report 04:10:04 INFO - PROCESS | 1676 | __start_cc_report 04:10:04 INFO - PROCESS | 1676 | _x_x_mozilla_cycle_collect,1249 04:10:04 INFO - PROCESS | 1676 | __end_cc_report 04:10:04 INFO - PROCESS | 1676 | __startTimestamp1461755404135__endTimestamp 04:10:04 INFO - PROCESS | 1676 | 04:10:04 INFO - PROCESS | 1676 | ------- Summary: start ------- 04:10:04 INFO - PROCESS | 1676 | Number of tests: 14 04:10:04 INFO - PROCESS | 1676 | 04:10:04 INFO - PROCESS | 1676 | [#0] ai-astar Cycles:10 Average:111.20 Median:111.00 stddev:3.01 (2.7%) stddev-sans-first:3.18 04:10:04 INFO - PROCESS | 1676 | Values: 112.0 109.0 109.0 109.0 111.0 112.0 119.0 111.0 109.0 111.0 04:10:04 INFO - PROCESS | 1676 | 04:10:04 INFO - PROCESS | 1676 | [#1] audio-beat-detection Cycles:10 Average:138.30 Median:149.50 stddev:18.32 (12.3%) stddev-sans-first:18.53 04:10:04 INFO - PROCESS | 1676 | Values: 154.0 125.0 151.0 147.0 114.0 161.0 114.0 150.0 149.0 118.0 04:10:04 INFO - PROCESS | 1676 | 04:10:04 INFO - PROCESS | 1676 | [#2] audio-dft Cycles:10 Average:185.90 Median:186.00 stddev:1.66 (0.9%) stddev-sans-first:1.76 04:10:04 INFO - PROCESS | 1676 | Values: 186.0 186.0 185.0 187.0 189.0 184.0 186.0 187.0 186.0 183.0 04:10:04 INFO - PROCESS | 1676 | 04:10:04 INFO - PROCESS | 1676 | [#3] audio-fft Cycles:10 Average:73.90 Median:58.50 stddev:24.44 (41.8%) stddev-sans-first:25.93 04:10:04 INFO - PROCESS | 1676 | Values: 74.0 71.0 77.0 117.0 53.0 56.0 57.0 60.0 118.0 56.0 04:10:04 INFO - PROCESS | 1676 | 04:10:04 INFO - PROCESS | 1676 | [#4] audio-oscillator Cycles:10 Average:95.50 Median:81.00 stddev:19.07 (23.5%) stddev-sans-first:20.16 04:10:04 INFO - PROCESS | 1676 | Values: 91.0 92.0 130.0 81.0 80.0 89.0 117.0 76.0 81.0 118.0 04:10:04 INFO - PROCESS | 1676 | 04:10:04 INFO - PROCESS | 1676 | [#5] imaging-gaussian-blur Cycles:10 Average:85.70 Median:87.00 stddev:2.58 (3.0%) stddev-sans-first:2.70 04:10:04 INFO - PROCESS | 1676 | Values: 87.0 82.0 88.0 90.0 87.0 83.0 87.0 84.0 86.0 83.0 04:10:04 INFO - PROCESS | 1676 | 04:10:04 INFO - PROCESS | 1676 | [#6] imaging-darkroom Cycles:10 Average:92.60 Median:93.00 stddev:0.70 (0.8%) stddev-sans-first:0.71 04:10:04 INFO - PROCESS | 1676 | Values: 92.0 92.0 92.0 93.0 93.0 93.0 92.0 94.0 93.0 92.0 04:10:04 INFO - PROCESS | 1676 | 04:10:04 INFO - PROCESS | 1676 | [#7] imaging-desaturate Cycles:10 Average:86.00 Median:86.50 stddev:0.94 (1.1%) stddev-sans-first:0.93 04:10:04 INFO - PROCESS | 1676 | Values: 85.0 87.0 87.0 87.0 86.0 86.0 85.0 85.0 85.0 87.0 04:10:04 INFO - PROCESS | 1676 | 04:10:04 INFO - PROCESS | 1676 | [#8] json-parse-financial Cycles:10 Average:57.20 Median:57.00 stddev:4.44 (7.8%) stddev-sans-first:4.64 04:10:04 INFO - PROCESS | 1676 | Values: 55.0 57.0 57.0 56.0 58.0 58.0 57.0 68.0 56.0 50.0 04:10:04 INFO - PROCESS | 1676 | 04:10:04 INFO - PROCESS | 1676 | [#9] json-stringify-tinderbox Cycles:10 Average:67.50 Median:66.00 stddev:5.30 (8.0%) stddev-sans-first:5.07 04:10:04 INFO - PROCESS | 1676 | Values: 61.0 65.0 64.0 79.0 73.0 71.0 66.0 66.0 65.0 65.0 04:10:04 INFO - PROCESS | 1676 | 04:10:04 INFO - PROCESS | 1676 | [#10] stanford-crypto-aes Cycles:10 Average:85.30 Median:81.00 stddev:8.38 (10.3%) stddev-sans-first:8.74 04:10:04 INFO - PROCESS | 1676 | Values: 81.0 81.0 80.0 80.0 80.0 94.0 105.0 89.0 80.0 83.0 04:10:04 INFO - PROCESS | 1676 | 04:10:04 INFO - PROCESS | 1676 | [#11] stanford-crypto-ccm Cycles:10 Average:122.70 Median:126.00 stddev:6.13 (4.9%) stddev-sans-first:6.47 04:10:04 INFO - PROCESS | 1676 | Values: 121.0 128.0 127.0 117.0 115.0 118.0 125.0 116.0 133.0 127.0 04:10:04 INFO - PROCESS | 1676 | 04:10:04 INFO - PROCESS | 1676 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:140.00 Median:134.00 stddev:17.78 (13.3%) stddev-sans-first:18.76 04:10:04 INFO - PROCESS | 1676 | Values: 135.0 132.0 176.0 170.0 131.0 123.0 134.0 134.0 134.0 131.0 04:10:04 INFO - PROCESS | 1676 | 04:10:04 INFO - PROCESS | 1676 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.50 Median:71.50 stddev:11.18 (15.6%) stddev-sans-first:11.61 04:10:04 INFO - PROCESS | 1676 | Values: 67.0 65.0 67.0 64.0 67.0 79.0 91.0 94.0 76.0 65.0 04:10:04 INFO - PROCESS | 1676 | -------- Summary: end -------- 04:10:04 INFO - PROCESS | 1676 | 04:10:04 INFO - TEST-INFO | 1676: exit 0 04:10:04 INFO - TEST-OK | kraken | took 56452ms 04:10:04 INFO - SUITE-END | took 1168s 04:10:05 INFO - Completed test suite (00:19:29) 04:10:05 INFO - javascript benchmark 04:10:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3335.266241426886, 3442.414789485789, 3457.3431048100874, 3465.740069494788, 3505.7195424366055, 5635.746391412386, 5640.830775076749, 5641.802536911209, 5656.434356564347, 5666.32004159501, 5624.100143976964, 5660.688662123582, 5675.404082571333, 5678.971605141968, 5694.373618901917, 5025.7235852028125, 5058.595312375001, 5123.5388815006645, 5128.307678463407, 5162.22566615007, 4987.925181122286, 5200.401953775307, 5212.0097181535575, 5216.530512253897, 5222.869428264305, 5116.718580478072, 5131.999260144274, 5160.458151894053, 5166.509181627739, 5201.2458193561915, 5195.740212989354, 5213.843584692465, 5222.399424066243, 5231.503007214346, 5263.0526505229045, 4945.554900059012, 5103.872403189913, 5113.642045056848, 5113.923291150636, 5149.356330458692, 5518.475744804237, 5581.5255703265275, 5591.077472217079, 5610.915836262459, 5626.212330273765, 6031.698415079272, 6215.098810672473, 6237.844053898643, 6238.500919926395, 6239.06414037898, 6143.354947730469, 6162.7534898604, 6188.164597779287, 6190.380961903818, 6300.432961033529, 5743.971280143614, 5772.538196944234, 5772.682502462321, 5777.075667893117, 5785.797497087582, 6272.9928317198965, 6284.780032698834, 6322.430981211713, 6327.209098862642, 6328.6835658216905, 5923.763049477972, 5953.0, 5987.042073268343, 6039.053342976186, 6056.545759068087, 6207.852839072648, 6235.657038862906, 6242.313345531988, 6246.406591373812, 6246.562740608205, 6103.176071230416, 6124.724387402578, 6159.199304090529, 6179.938200618026, 6198.256209254918, 6010.068439391901, 6091.02543593023, 6113.354685976905, 6193.102000209944, 6196.969015154986, 4851.369321988119, 4859.781309841065, 4873.366462359942, 4875.902481950412, 4894.290327902434, 4376.343548467756, 4385.0, 4401.933970990438, 4413.293872980308, 4430.335449682509, 5508.944910550923, 5566.860828479321, 5604.943950560524, 5631.2960879890015, 5666.660000399989, 6265.310815810257, 6271.905921411182, 6272.6550039747335, 6297.937020629826, 6302.527310451735, 5587.8603034923635, 5638.661680299195, 5658.462446067617, 5660.377358490563, 5696.572757043238, 5145.151050076695, 5313.44208858072, 5314.7608357624, 5347.117725575315, 5380.704061276589, 6252.499800015988, 6287.088372186007, 6289.150964619809, 6289.245290565162, 6303.558750887486, 6255.96872015637, 6281.246250449975, 6293.748250069946, 6297.2128483939505, 6306.936930630818, 6219.253689557282, 6232.594881332699, 6272.40412160844, 6295.905561416491, 6305.464035557029, 6146.078088286793, 6163.075538669056, 6168.9691551541955, 6178.752849885954, 6196.938030619816, 5054.862655902392, 6004.939950600613, 6287.497000239879, 6294.905576416266, 6312.210973628296, 5969.820905372846, 6050.6369617823075, 6070.2108725865355, 6084.78703245402, 6101.0, 6023.548233882389, 6052.848678783065, 6060.393960603904, 6065.029595264737, 6069.605475644069, 5779.133130030529, 5881.823545293648, 5886.028805247258, 5900.616459930091, 5908.350081490947, 5632.464915832989, 6042.123892035718, 6059.878802424015, 6082.300535438481, 6108.053251745987, 5843.2403787507355, 5873.471387575139, 5874.706264686663, 5882.882342353043, 5883.235179426647], "unit": "score", "name": "dojo.html", "value": 5656.32545295332}, {"lowerIsBetter": false, "replicates": [504.2159442067588, 594.8875602623095, 602.8395338972476, 609.9085137229415, 640.8526039011027, 31777.0, 32406.351872962558, 32415.86544470944, 32425.675743242562, 32448.1887952801, 33485.82799602015, 42443.57556424435, 42702.35946460805, 42849.57150428495, 42871.142577148436, 9559.904400956006, 9728.124468797807, 9733.902660973406, 9742.318037737326, 9752.024797520227, 9606.519674016306, 9725.076117768836, 9736.026397360278, 9765.12113909748, 9768.560414781316, 9706.223502119814, 9787.119159275664, 9787.706368808947, 9798.657047003357, 9819.558119884587, 9671.323007389485, 9883.505824708807, 9885.802283954317, 9892.95053524735, 9897.604095836194, 8275.758636204557, 9728.221742260603, 9853.014698530162, 9858.950705246429, 9859.753506162331, 40994.0, 43344.783276083734, 43363.34954975678, 43391.0, 43404.56595434037, 3215.5980502437196, 3517.7009954153928, 3540.4689296605457, 3644.69020133289, 3849.9230015399817, 28705.56941645877, 29517.70482295171, 30049.248768780955, 30195.849020754977, 30441.543376849364, 2775.514285000137, 2793.52510073288, 2828.420173864353, 2839.5172820620337, 2865.6274684291116, 416539.0, 419458.90270548756, 419534.0, 420004.0, 420157.0, 47695.52304476911, 48841.75579122153, 69004.61990760156, 69601.95597066048, 69726.0, 46812.297815532795, 46921.0, 47089.52910470851, 47093.29360059602, 47109.0, 16479.92880462766, 16644.91808032474, 16649.16754162287, 16655.91672041632, 16663.416975387318, 13264.734705306033, 13362.665933351549, 13368.064235503422, 13368.33158342079, 13371.866281337063, 14998.850011499746, 15023.399064037316, 15044.699106017819, 15061.171635559931, 15158.242087895562, 6416.518761092843, 6511.511636627272, 6554.082428460019, 6555.082288479617, 6576.539642225094, 980.2305190425624, 1042.864427624404, 1097.4896673047072, 1135.4947048563474, 1154.584349634131, 29527.704722952923, 30350.696493034786, 30441.39117217643, 30760.846195768878, 31015.069547913597, 1183.7277426192024, 1213.6662417835166, 1253.5988483685317, 1266.8226448297246, 1274.1781550899618, 8882.86675699865, 16108.355665773472, 16268.0, 16497.587560311087, 17002.404915827887, 520107.0, 588272.0, 732819.0, 733524.0, 734579.0, 64956.025659615145, 65158.674206629614, 65670.0149497758, 65791.67104164448, 65956.01065984015, 1422.3954819201797, 1472.0578829549097, 1489.5307977986683, 1630.8287629798938, 1668.8414600612923, 28433.715662843933, 30829.075127746204, 33357.16607084745, 34137.65862341444, 34227.14432139117, 58782.4121758777, 59244.0, 59369.406305938115, 59464.10803838033, 59482.107768382644, 406377.0, 419414.0, 419886.0, 420395.0, 420713.8964305159, 30409.783608655405, 35165.296694066485, 35380.82309588538, 35600.821995889855, 36152.0, 47718.04563908633, 47985.5201447981, 48072.79818004578, 48095.0, 49529.0, 38118.42822357611, 39054.02364940899, 39055.828325150294, 39260.41109383304, 40618.9845253871, 37495.812520938314, 37867.0, 38107.23785524219, 38716.6128338713, 38875.416868746426, 39423.0, 40583.98540036404, 40862.38706419346, 42066.57933420627, 42098.36852447277, 31604.0, 34324.74882201909, 34621.826890865384, 34648.480272796434, 34863.77976770801, 25706.357341065876, 25903.481930361664, 26631.733682662925, 26847.4494922982, 27092.32269193286], "unit": "score", "name": "ext.html", "value": 19990.691653924638}, {"lowerIsBetter": false, "replicates": [377.98299076541554, 382.29085047237373, 386.58442174662247, 389.9337112690842, 394.1899396739699, 16334.509964701077, 16630.0, 19756.617036807413, 20623.2781852635, 21095.789042109573, 16571.337146514154, 20740.066696998634, 20888.791112088875, 20967.0, 21013.054412551435, 5795.971020144893, 5855.941440585582, 5906.409359064102, 5910.202122713443, 5913.615614985034, 5600.103983362663, 5759.9136012959625, 5831.271091113611, 5865.76536938522, 5870.88258234835, 5837.766489340422, 5851.6196447231005, 5860.912086318709, 5864.061750119981, 5867.88264234715, 5033.924491132636, 5497.255368140303, 5823.039198532237, 5828.854278643025, 5830.82507524775, 5534.169874518814, 5546.667199968015, 5575.665460072409, 5578.219049333096, 5598.188262701926, 13843.377048032862, 23736.881315593484, 23809.166679166145, 24581.262562123164, 24659.87670061656, 2096.423483542038, 2215.6898034275214, 2918.4017276458276, 3115.8286294253808, 3156.416063028324, 15158.772618410732, 15458.304376303093, 15483.0, 15838.812089093364, 15989.440369587124, 1817.3639226270832, 1867.9346222882134, 1890.546268895469, 1933.3426634944187, 1947.250308631173, 109749.90250097477, 115884.0, 118305.81694183034, 119878.40060799728, 126166.0, 19270.518237044187, 19393.127309271116, 19445.708314375297, 19493.978433342854, 19535.804641953684, 29839.701602983692, 29853.0, 30417.391652167276, 30491.085267442013, 30560.84719576388, 9179.495127768037, 10678.73303167427, 10940.890591093987, 11052.834207486894, 11078.279911805706, 9006.729798106067, 9027.954860225656, 9065.637374504946, 9112.316576256808, 9583.952080239555, 7696.807579810549, 8330.583470826434, 8611.741647750578, 8627.396082274181, 8719.389642725075, 2952.867120979561, 2961.940761184764, 3074.5388191771412, 3159.921001974969, 3478.339115568034, 780.6994307191773, 917.1104029091904, 970.9223262139012, 974.181687382602, 1045.435464848973, 19755.308564200182, 20663.276785312442, 20727.963601819847, 20737.377878663665, 20767.8961605191, 1025.477006726575, 1026.2508368890751, 1026.830572955454, 1074.2319241742036, 1125.099920063962, 10697.0, 11114.166437517219, 11121.165007773627, 11146.665600032013, 11169.329840209613, 606396.0, 614987.0, 618599.9070004711, 626826.0, 627450.0, 112430.0, 114089.0, 114119.0, 119314.0, 123613.0, 1038.3354653022072, 1063.8510608514814, 1075.3118004477142, 1075.8386242063752, 1077.1329080090602, 17638.823611763717, 17683.313288186026, 17744.3789467368, 18047.458576242734, 18264.634707306046, 49204.507954919995, 49720.751396242784, 51685.483145168066, 52004.0, 52877.47122528879, 45582.31626525671, 46709.0, 70655.29344706488, 81453.18546814455, 81471.18528814873, 7922.920770792219, 8082.87875681877, 8161.510309381456, 8206.753797386087, 8292.792680183044, 12602.0, 12990.090693651353, 13116.278604676834, 13162.276074815594, 13310.86689133135, 11506.021988131102, 11517.366544840113, 11579.015783658484, 11613.419329033346, 11756.764864702829, 11460.312381257152, 11515.654530364103, 11652.766944660889, 11679.240849344766, 11872.703182420508, 11640.708982275512, 11643.708907277385, 11790.528378864748, 11911.285322880654, 11939.880601193878, 8252.711155109542, 8260.173982601691, 8347.290480309242, 8371.539565323723, 8456.154384561494, 8528.701495447629, 11010.889891100986, 11656.475458604553, 11706.88293117058, 11870.52517899309], "unit": "score", "name": "jquery.html", "value": 10426.264152012895}, {"lowerIsBetter": false, "replicates": [1519.528946026731, 1622.6998005369007, 1661.3105561192106, 1967.9409617711474, 2036.857419980602, 8360.3729720271, 8866.689665861697, 9054.185123338899, 9060.048694887024, 9078.818423631523, 8797.912020879789, 9056.81886362274, 9072.682456114037, 9075.183233508982, 9080.0, 2156.3423155937435, 2217.756046834847, 2516.6854143232094, 2566.627838963354, 2577.226831950417, 2583.1346498922885, 2590.0675756727564, 2595.364135786735, 2596.1692258477296, 2599.8960041598407, 2136.39112852837, 2500.3999040230306, 2576.1112416216374, 2580.213035024317, 2581.2901452100637, 2520.243926821955, 2560.6287088372173, 2580.019592554836, 2585.896564137432, 2586.9870650646626, 2536.353229926375, 2561.9880147341883, 2575.562154433751, 2580.4323048929205, 2585.0952166741677, 7782.805429864242, 9120.224780893566, 9189.264858811279, 9210.631574737003, 9225.815483690289, 5617.634853734535, 5725.484706376405, 5774.393688662714, 5782.56630752691, 5788.450097240755, 3002.4445477586496, 3618.3486972344967, 3620.5112309838096, 3648.762830416041, 3654.3787556115526, 1058.7882423515327, 1063.9308444951052, 1070.0690399352613, 1077.3374374759544, 1107.8172101603225, 12447.75104497914, 12451.315177665225, 12463.501459941592, 12467.999999999909, 12471.0, 4970.577500912463, 4989.80040798364, 5070.3155074064525, 5084.161113416319, 5089.541941225308, 10558.78573964004, 10584.417857017785, 10593.470326483644, 10600.734981625521, 10607.893921060844, 5727.140928860621, 5744.684042377626, 5751.051076572402, 5756.395578464285, 5759.913601295984, 4599.471060827981, 4762.64280178988, 4988.501149884983, 5108.66793658411, 5166.070107380708, 3975.6421922027157, 4061.8375264989068, 4075.89810254746, 4092.4066010428323, 4135.669146468275, 1344.153183494412, 1355.8440779310308, 1355.857634948336, 1365.2968721108637, 1366.1188533395905, 1308.5616318533398, 1338.2773302416585, 1392.4082265037318, 1460.4158336665207, 1462.0642788615294, 8393.286570641563, 8573.442726222776, 8585.914140858637, 8592.183742544448, 8594.097619749962, 533.9546138578264, 539.7409243563034, 550.4742970463197, 552.701541167765, 552.7982286465492, 5342.412334643186, 6333.303336632966, 6334.461570766536, 6369.0, 6384.904226436607, 10640.201984851166, 11013.2841365311, 11021.338719676685, 11121.443927803577, 11141.665750027512, 10587.629432969808, 10899.564017439372, 10952.561897524169, 10968.396738179317, 11098.889011109786, 8592.527410992581, 8594.484330940164, 8612.65549378018, 8684.044755076937, 8684.8697269541, 8113.1886811320755, 8142.592870356577, 8167.1424500428975, 8477.98264208299, 8490.108538603357, 9502.572384242863, 9880.35777674449, 9947.154491868272, 10010.549525271235, 10034.297599167989, 9862.309638325249, 10175.084242418217, 10345.017223363768, 10345.120664743281, 10369.0, 7781.572013539309, 7782.727604533814, 7783.104942931409, 7849.254320839511, 7859.567723775247, 8501.574921253788, 8514.91485085166, 8581.65673373058, 8596.742097736827, 8603.741887743377, 7483.32650061497, 7589.734359297398, 7659.387249019954, 7749.573773442515, 7842.882356764767, 7335.339819416278, 7427.4800763946005, 7485.288897554724, 7562.848743025219, 7600.239976002355, 7940.205979402013, 8004.239597238253, 8020.398470114881, 8020.919790802017, 8045.718399856211, 7176.0, 7206.135263768381, 7252.528585641917, 7295.343419092307, 7316.4146868249345, 7412.369948554433, 7427.665755040928, 7464.328210461084, 7467.962660186664, 7474.2525747427], "unit": "score", "name": "mootools.html", "value": 5017.887340415343}, {"lowerIsBetter": false, "replicates": [340.51476645779763, 346.5494856686307, 347.1183194685499, 350.14913759564257, 353.3322021379593, 6586.901196482057, 6626.635535045577, 6657.034729964153, 6702.832429189272, 6704.631245281507, 6579.407853293203, 6611.933880661192, 6622.304658010899, 6628.469722422206, 6645.833854153649, 4164.896302479845, 4723.834665786698, 4747.810087596492, 4887.364642596475, 4910.558049775519, 4849.636277279218, 4860.5139486051285, 4862.951370486286, 4880.267959806022, 4893.510648935114, 4786.186348320776, 4819.373481447407, 4823.951760482403, 4833.661643684926, 4835.347228124211, 3726.441033844918, 4736.0, 4736.0, 4775.761211939423, 4829.51704829516, 4086.0806318578084, 4167.166566686645, 4176.7076304658685, 4177.707560470768, 4186.183694179636, 5258.316418865523, 6180.6291622502795, 6311.747530098791, 6340.302566717657, 6363.236411630634, 5786.855328616776, 5788.479036886658, 5790.507806836382, 5794.333651630074, 5805.477507024388, 7003.15962084548, 7016.543924644881, 7019.017337572744, 7029.859402811915, 7039.296070392971, 4559.229943986057, 4752.59602933751, 4755.785989630475, 4765.28520721891, 4767.713937163781, 7353.1911489736085, 7369.299916507923, 7381.4094872410615, 7396.4452666050265, 7406.555606663617, 4833.516648335139, 4833.540813622701, 4835.347228124158, 4843.660943733905, 4849.733264670477, 4913.385826771653, 4916.3362946001835, 4925.310456536088, 4932.964077543756, 4935.407751069823, 5018.24726291052, 5480.520259529904, 5779.0, 5796.130580412888, 5804.187413762077, 6734.764283250062, 7001.089858318386, 7037.824054398681, 7064.858702825914, 7098.077249957472, 1332.7134666046652, 1633.020187887282, 1645.967080658404, 1646.5965838369675, 1647.0, 1036.3522798251092, 1046.4349251404155, 1060.7189094889861, 1061.994690026545, 1065.2383545764815, 5724.198612194296, 6243.282022567463, 6340.080688300171, 6546.279909209983, 6703.899441508382, 7393.297636724503, 7398.519096258726, 7401.48189626721, 7407.777766667009, 7433.739819106306, 6625.701843417058, 6628.635425051572, 6629.303923088007, 6633.70148343316, 6640.468762498988, 3866.1107945172766, 3912.56961734209, 3913.491246138041, 3915.2169566086327, 3922.0783115967724, 7201.667691477093, 7378.852422951511, 7660.502067365604, 7681.846363072707, 7727.343175830005, 6714.966425167843, 6786.59280443175, 7030.578165309996, 7590.734324298623, 7609.1085387553485, 7661.885071723928, 7676.19399963007, 7690.999999999888, 7697.4226932980255, 7700.614969251627, 7356.926430735838, 7357.668904899399, 7366.189719130998, 7368.631568421664, 7370.115586129698, 6930.61881596517, 7174.569525828467, 7224.096987876515, 7225.494215404871, 7233.132024157135, 7658.885116723142, 7683.538987660758, 7688.269614386624, 7692.461527693008, 7698.499597526143, 4698.577128058491, 4711.434627844681, 4713.434387873477, 4716.0, 4722.858314250578, 7356.77929662111, 7360.33756961876, 7376.96311518439, 7392.186859445565, 7401.148867880047, 6752.628605426748, 7105.928940710527, 7112.50212485121, 7117.893231601426, 7118.466115041496, 7023.403010744144, 7074.929250707427, 7080.539764915264, 7088.0785497884945, 7094.716211351488, 6927.515073944977, 6940.09778728762, 6954.1404520491615, 6955.0, 6960.408365288805, 6780.762673306411, 7070.71717131309, 7082.220955694766, 7095.3968912643, 7109.040279562193, 6654.866902662016, 6850.4862135341045, 6931.0, 6942.826429339307, 6950.582965021913], "unit": "score", "name": "prototype.html", "value": 5175.45661975709}, {"lowerIsBetter": false, "replicates": [704.4012589299097, 717.9282071792821, 733.765195137556, 770.4606775257314, 775.3990657240638, 15203.0, 15248.466303679374, 15419.614509637264, 15442.99620524665, 15458.536243912673, 14367.209803460832, 14447.422103115863, 14457.927710361433, 14459.132452052858, 14555.0, 783.6121120045572, 798.9840203195962, 821.1008945204991, 951.6907005223295, 993.6869885985891, 969.5830792759111, 976.077606661705, 977.1449981266392, 977.3940157102571, 977.5747549815817, 1041.3751748950606, 1045.2369770067855, 1046.539522610049, 1046.848207009983, 1048.035807057505, 831.1314676163423, 1026.5688410867454, 1056.4136904018267, 1056.4136904018267, 1056.4981633723996, 780.7657702689171, 784.5292824305428, 785.1089013969129, 785.2422412372031, 785.7603430953557, 15603.765943510856, 15674.294656740474, 15685.294161762748, 15699.843001570067, 15724.764128538081, 12973.96208303343, 13257.933710331386, 13342.599722008355, 13352.666183345396, 13507.86492135076, 10042.297039207258, 10219.591216351337, 10250.231232657581, 10541.31481453703, 10620.415877126756, 3593.245418462126, 3594.4967704521387, 3646.58064322601, 3708.684761795251, 3879.243547508237, 78646.6067669658, 80216.0, 82110.58944705297, 82633.58683206546, 82899.17100828975, 9915.011354958895, 10104.191664666809, 10207.693769186937, 10288.948555257175, 10290.948545257226, 23623.055077796696, 23643.645345319834, 23689.407764805674, 23708.762912371, 23733.16933907305, 7592.050993625797, 7654.6221680098115, 7762.611869406507, 7855.057393112749, 7890.250426209501, 4126.91746165081, 4135.400366946776, 4200.4539409876525, 4265.936010959838, 4294.527601963781, 3747.569029561581, 3777.395616701315, 3798.164403831153, 3801.7148713846573, 3812.790296533717, 580.6167929166729, 601.3895895665903, 679.239252037711, 696.1437431958719, 748.8839229919371, 505.2648396582963, 594.3313772006493, 604.4469310580857, 610.2128254551667, 618.6937465954292, 8155.551444670481, 8377.371697122619, 8430.352860227, 8481.618327175293, 8525.104863989192, 524.9921251181236, 597.5846786483439, 597.6175247841385, 615.4553220399978, 625.2996643758928, 3872.593377695358, 3910.648041676206, 3911.1786524829536, 4021.5375231848125, 4054.8378064877666, 91884.54057729669, 91961.0, 93006.0, 93286.53356733173, 93337.53331233436, 80539.59730201428, 80820.59589702013, 81382.0, 82239.5888020556, 83291.0, 72056.0, 72438.63780680958, 73407.0, 74192.88710669344, 74600.0, 7762.3790096793255, 7841.882371764542, 7987.40094492927, 8120.959395202986, 8330.04204516464, 51715.0, 52540.42378728644, 52540.73729631327, 52921.470785291654, 53960.65098372572, 66062.66968665126, 68413.31586684269, 70441.0, 70575.94136088065, 73378.0, 10329.586816527255, 10664.306820056672, 10740.785184296426, 10776.568937242422, 10914.399708016135, 10751.784964300827, 10955.0, 10968.561257549609, 11181.944090279496, 11196.66410007701, 10429.582816687247, 10536.473176341306, 10544.841827372442, 10722.51748671296, 10835.783284334428, 10526.736831578966, 10577.576896924036, 10772.946135269274, 10804.891951080703, 10867.402292873652, 10154.984501550081, 10271.84592231132, 10338.276320657482, 10577.894221057692, 10616.097631701392, 9008.369414140947, 9013.819723605622, 9025.684101056431, 9197.54012299369, 9231.630734770803, 8990.460572365679, 9084.636614535344, 9114.04302548223, 9140.6800761973, 9250.306227033134], "unit": "score", "name": "yui.html", "value": 7036.290733683979}], "name": "dromaeo_css", "value": 7742.656867947034}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [112.0, 109.0, 109.0, 109.0, 111.0, 112.0, 119.0, 111.0, 109.0, 111.0], "unit": "score", "name": "ai-astar", "value": 111.2}, {"lowerIsBetter": true, "replicates": [154.0, 125.0, 151.0, 147.0, 114.0, 161.0, 114.0, 150.0, 149.0, 118.0], "unit": "score", "name": "audio-beat-detection", "value": 138.3}, {"lowerIsBetter": true, "replicates": [186.0, 186.0, 185.0, 187.0, 189.0, 184.0, 186.0, 187.0, 186.0, 183.0], "unit": "score", "name": "audio-dft", "value": 185.9}, {"lowerIsBetter": true, "replicates": [74.0, 71.0, 77.0, 117.0, 53.0, 56.0, 57.0, 60.0, 118.0, 56.0], "unit": "score", "name": "audio-fft", "value": 73.9}, {"lowerIsBetter": true, "replicates": [91.0, 92.0, 130.0, 81.0, 80.0, 89.0, 117.0, 76.0, 81.0, 118.0], "unit": "score", "name": "audio-oscillator", "value": 95.5}, {"lowerIsBetter": true, "replicates": [87.0, 82.0, 88.0, 90.0, 87.0, 83.0, 87.0, 84.0, 86.0, 83.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 85.7}, {"lowerIsBetter": true, "replicates": [92.0, 92.0, 92.0, 93.0, 93.0, 93.0, 92.0, 94.0, 93.0, 92.0], "unit": "score", "name": "imaging-darkroom", "value": 92.6}, {"lowerIsBetter": true, "replicates": [85.0, 87.0, 87.0, 87.0, 86.0, 86.0, 85.0, 85.0, 85.0, 87.0], "unit": "score", "name": "imaging-desaturate", "value": 86.0}, {"lowerIsBetter": true, "replicates": [55.0, 57.0, 57.0, 56.0, 58.0, 58.0, 57.0, 68.0, 56.0, 50.0], "unit": "score", "name": "json-parse-financial", "value": 57.2}, {"lowerIsBetter": true, "replicates": [61.0, 65.0, 64.0, 79.0, 73.0, 71.0, 66.0, 66.0, 65.0, 65.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.5}, {"lowerIsBetter": true, "replicates": [81.0, 81.0, 80.0, 80.0, 80.0, 94.0, 105.0, 89.0, 80.0, 83.0], "unit": "score", "name": "stanford-crypto-aes", "value": 85.3}, {"lowerIsBetter": true, "replicates": [121.0, 128.0, 127.0, 117.0, 115.0, 118.0, 125.0, 116.0, 133.0, 127.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 122.7}, {"lowerIsBetter": true, "replicates": [135.0, 132.0, 176.0, 170.0, 131.0, 123.0, 134.0, 134.0, 134.0, 131.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 140.0}, {"lowerIsBetter": true, "replicates": [67.0, 65.0, 67.0, 64.0, 67.0, 79.0, 91.0, 94.0, 76.0, 65.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 73.5}], "name": "kraken", "value": 1415.3000000000002}]} 04:10:05 INFO - Return code: 0 04:10:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 04:10:05 INFO - # TBPL SUCCESS # 04:10:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:10:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:10:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:10:05 INFO - 2016-04-27 04:10:05.441 screenresolution[1680:10012] starting screenresolution argv=screenresolution get 04:10:05 INFO - 2016-04-27 04:10:05.443 screenresolution[1680:10012] Display 0: 1600x1200x32@60 04:10:05 INFO - 2016-04-27 04:10:05.449 screenresolution[1681:10015] starting screenresolution argv=screenresolution list 04:10:05 INFO - Available Modes on Display 0 04:10:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:10:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:10:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:10:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:10:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:10:05 INFO - Intel Iris: 04:10:05 INFO - Chipset Model: Intel Iris 04:10:05 INFO - Type: GPU 04:10:05 INFO - Bus: Built-In 04:10:05 INFO - VRAM (Dynamic, Max): 1536 MB 04:10:05 INFO - Vendor: Intel (0x8086) 04:10:05 INFO - Device ID: 0x0a2e 04:10:05 INFO - Revision ID: 0x0009 04:10:05 INFO - Displays: 04:10:05 INFO - Display: 04:10:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:10:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:10:05 INFO - Display Serial Number: 1600x1200 60 04:10:05 INFO - Main Display: Yes 04:10:05 INFO - Mirror: Off 04:10:05 INFO - Online: Yes 04:10:05 INFO - Rotation: Supported 04:10:05 INFO - Return code: 0 04:10:05 INFO - Running post-action listener: _resource_record_post_action 04:10:05 INFO - Running post-run listener: _resource_record_post_run 04:10:06 INFO - Total resource usage - Wall time: 1203s; CPU: 53.0%; Read bytes: 38008320; Write bytes: 663154688; Read time: 404; Write time: 1949 04:10:06 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 362631680; Write bytes: 364247040; Read time: 29727; Write time: 733 04:10:06 INFO - run-tests - Wall time: 1171s; CPU: 52.0%; Read bytes: 36189696; Write bytes: 297375744; Read time: 383; Write time: 1212 04:10:06 INFO - Running post-run listener: _upload_blobber_files 04:10:06 INFO - Blob upload gear active. 04:10:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:10:06 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 04:10:06 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:10:06 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:10:07 INFO - (blobuploader) - INFO - Open directory for files ... 04:10:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:10:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:10:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:10:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:10:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:10:08 INFO - (blobuploader) - INFO - Done attempting. 04:10:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:10:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:10:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:10:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:10:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:10:09 INFO - (blobuploader) - INFO - Done attempting. 04:10:09 INFO - (blobuploader) - INFO - Iteration through files over. 04:10:09 INFO - Return code: 0 04:10:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:10:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:10:09 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2de6db0581d2d3d5616aa0ad19f82c8ad4f6d53d32d334705deb2f01807c4ec6065636d9a19010ef35ea380144ab1220332185c455732d9a9742ac4a9263ba22", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3470cccb7a7f1c321351e8655439457fc57035dcc4256530e423c155afee8bc60b16d89e440ec965e49b9f319a9a0ce0c6733b6b30d719bc02f676b8abffb984"} 04:10:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:10:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:10:09 INFO - Contents: 04:10:09 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2de6db0581d2d3d5616aa0ad19f82c8ad4f6d53d32d334705deb2f01807c4ec6065636d9a19010ef35ea380144ab1220332185c455732d9a9742ac4a9263ba22", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3470cccb7a7f1c321351e8655439457fc57035dcc4256530e423c155afee8bc60b16d89e440ec965e49b9f319a9a0ce0c6733b6b30d719bc02f676b8abffb984"} 04:10:09 INFO - Running post-run listener: copy_logs_to_upload_dir 04:10:09 INFO - Copying logs to upload dir... 04:10:09 INFO - mkdir: /builds/slave/test/build/upload/logs 04:10:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1259.509061 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 59 secs) (at 2016-04-27 04:10:09.309653) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-27 04:10:09.313285) ========= 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.troASljsGd/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.UnJSvazDkE/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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/Mozilla-Aurora/sha512/2de6db0581d2d3d5616aa0ad19f82c8ad4f6d53d32d334705deb2f01807c4ec6065636d9a19010ef35ea380144ab1220332185c455732d9a9742ac4a9263ba22", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3470cccb7a7f1c321351e8655439457fc57035dcc4256530e423c155afee8bc60b16d89e440ec965e49b9f319a9a0ce0c6733b6b30d719bc02f676b8abffb984"} build_url:https://queue.taskcluster.net/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010928 build_url: 'https://queue.taskcluster.net/v1/task/Og0VB9dDR6qRbf5zAwFhyg/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2de6db0581d2d3d5616aa0ad19f82c8ad4f6d53d32d334705deb2f01807c4ec6065636d9a19010ef35ea380144ab1220332185c455732d9a9742ac4a9263ba22", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3470cccb7a7f1c321351e8655439457fc57035dcc4256530e423c155afee8bc60b16d89e440ec965e49b9f319a9a0ce0c6733b6b30d719bc02f676b8abffb984"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-27 04:10:09.343544) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 04:10:09.344133) ========= 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.troASljsGd/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.UnJSvazDkE/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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.005195 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 04:10:09.394553) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-27 04:10:09.394991) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-27 04:10:09.395537) ========= ========= Total master_lag: 0.16 =========