builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0195 starttime: 1463686448.84 results: success (0) buildid: 20160519112644 builduid: 2cc4a461a08945b0bc74af7d59616906 revision: 133e9db008eadefc4426a3731d30f60f0e290a76 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-19 12:34:08.836612) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-19 12:34:08.837036) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-19 12:34:08.837310) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-19 12:34:08.858291) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 12:34:08.858561) ========= 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.nq8uSYTVM0/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.QnazZYSwtU/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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-05-19 12:34:08-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 161M=0s 2016-05-19 12:34:08 (161 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.121691 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 12:34:08.999608) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 12:34:08.999981) ========= 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.nq8uSYTVM0/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.QnazZYSwtU/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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.026712 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 12:34:09.048045) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 12:34:09.048364) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 133e9db008eadefc4426a3731d30f60f0e290a76 --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 133e9db008eadefc4426a3731d30f60f0e290a76 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nq8uSYTVM0/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.QnazZYSwtU/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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-05-19 12:34:09,120 truncating revision to first 12 chars 2016-05-19 12:34:09,120 Setting DEBUG logging. 2016-05-19 12:34:09,120 attempt 1/10 2016-05-19 12:34:09,121 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/133e9db008ea?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-19 12:34:09,787 unpacking tar archive at: mozilla-aurora-133e9db008ea/testing/mozharness/ program finished with exit code 0 elapsedTime=0.915755 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 12:34:09.981900) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-19 12:34:09.982222) ========= script_repo_revision: 133e9db008eadefc4426a3731d30f60f0e290a76 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-19 12:34:09.982618) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-19 12:34:09.982884) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-19 12:34:09.997662) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 47 mins, 17 secs) (at 2016-05-19 12:34:09.997932) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.nq8uSYTVM0/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.QnazZYSwtU/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 12:34:10 INFO - MultiFileLogger online at 20160519 12:34:10 in /builds/slave/test 12:34:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 12:34:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:34:10 INFO - {'append_to_log': False, 12:34:10 INFO - 'base_work_dir': '/builds/slave/test', 12:34:10 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 12:34:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:34:10 INFO - 'branch': 'Mozilla-Aurora', 12:34:10 INFO - 'buildbot_json_path': 'buildprops.json', 12:34:10 INFO - 'config_files': ('talos/mac_config.py',), 12:34:10 INFO - 'default_actions': ('clobber', 12:34:10 INFO - 'read-buildbot-config', 12:34:10 INFO - 'download-and-extract', 12:34:10 INFO - 'populate-webroot', 12:34:10 INFO - 'create-virtualenv', 12:34:10 INFO - 'install', 12:34:10 INFO - 'run-tests'), 12:34:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:34:10 INFO - 'download_minidump_stackwalk': True, 12:34:10 INFO - 'download_symbols': 'ondemand', 12:34:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:34:10 INFO - 'tooltool.py': '/tools/tooltool.py', 12:34:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:34:10 INFO - '/tools/misc-python/virtualenv.py')}, 12:34:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:34:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:34:10 INFO - 'installer_path': 'installer.exe', 12:34:10 INFO - 'log_level': 'info', 12:34:10 INFO - 'log_name': 'talos', 12:34:10 INFO - 'log_to_console': True, 12:34:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:34:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:34:10 INFO - 'opt_config_files': (), 12:34:10 INFO - 'pip_index': False, 12:34:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:34:10 INFO - 'cmd': ('bash', 12:34:10 INFO - '-c', 12:34:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:34:10 INFO - 'enabled': True, 12:34:10 INFO - 'halt_on_failure': False, 12:34:10 INFO - 'name': 'check_screen_resolution'},), 12:34:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:34:10 INFO - 'cmd': ('bash', 12:34:10 INFO - '-c', 12:34:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:34:10 INFO - 'enabled': True, 12:34:10 INFO - 'halt_on_failure': False, 12:34:10 INFO - 'name': 'check_screen_resolution'},), 12:34:10 INFO - 'run_cmd_checks_enabled': True, 12:34:10 INFO - 'sps_profile': False, 12:34:10 INFO - 'sps_profile_interval': 0, 12:34:10 INFO - 'suite': 'dromaeojs-e10s', 12:34:10 INFO - 'system_bits': '32', 12:34:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:34:10 INFO - 'title': 't-yosemite-r7-0195', 12:34:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:34:10 INFO - 'use_talos_json': True, 12:34:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:34:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:34:10 INFO - 'work_dir': 'build'} 12:34:10 INFO - ##### 12:34:10 INFO - ##### Running clobber step. 12:34:10 INFO - ##### 12:34:10 INFO - Running pre-action listener: _resource_record_pre_action 12:34:10 INFO - Running main action method: clobber 12:34:10 INFO - rmtree: /builds/slave/test/build 12:34:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:34:12 INFO - Running post-action listener: _resource_record_post_action 12:34:12 INFO - ##### 12:34:12 INFO - ##### Running read-buildbot-config step. 12:34:12 INFO - ##### 12:34:12 INFO - Running pre-action listener: _resource_record_pre_action 12:34:12 INFO - Running main action method: read_buildbot_config 12:34:12 INFO - Using buildbot properties: 12:34:12 INFO - { 12:34:12 INFO - "project": "", 12:34:12 INFO - "product": "firefox", 12:34:12 INFO - "script_repo_revision": "production", 12:34:12 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 12:34:12 INFO - "repository": "", 12:34:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 12:34:12 INFO - "buildid": "20160519112644", 12:34:12 INFO - "pgo_build": "False", 12:34:12 INFO - "basedir": "/builds/slave/test", 12:34:12 INFO - "buildnumber": 332, 12:34:12 INFO - "slavename": "t-yosemite-r7-0195", 12:34:12 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 12:34:12 INFO - "platform": "yosemite_r7", 12:34:12 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 12:34:12 INFO - "branch": "mozilla-aurora", 12:34:12 INFO - "repo_path": "releases/mozilla-aurora", 12:34:12 INFO - "revision": "133e9db008eadefc4426a3731d30f60f0e290a76", 12:34:12 INFO - "stage_platform": "macosx64", 12:34:12 INFO - "builduid": "2cc4a461a08945b0bc74af7d59616906", 12:34:12 INFO - "slavebuilddir": "test" 12:34:12 INFO - } 12:34:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 12:34:12 INFO - Running post-action listener: _resource_record_post_action 12:34:12 INFO - ##### 12:34:12 INFO - ##### Running download-and-extract step. 12:34:12 INFO - ##### 12:34:12 INFO - Running pre-action listener: _resource_record_pre_action 12:34:12 INFO - Running main action method: download_and_extract 12:34:12 INFO - mkdir: /builds/slave/test/build/tests 12:34: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')]} 12:34:12 INFO - https://queue.taskcluster.net/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 12:34:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 12:34:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 12:34:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 12:34:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 12:34:14 INFO - Downloaded 1199 bytes. 12:34:14 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 12:34:14 INFO - Using the following test package requirements: 12:34:14 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 12:34:14 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:34:14 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 12:34:14 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:34:14 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 12:34:14 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:34:14 INFO - u'jsshell-mac.zip'], 12:34:14 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:34:14 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 12:34:14 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 12:34:14 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:34:14 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 12:34:14 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:34:14 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 12:34:14 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:34:14 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 12:34:14 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:34:14 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 12:34:14 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 12:34:14 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')]} 12:34:14 INFO - https://queue.taskcluster.net/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:34:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 12:34:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 12:34:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 12:34:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 12:34:16 INFO - Downloaded 18102376 bytes. 12:34:16 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:34:16 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 12:34:19 INFO - Return code: 0 12:34:19 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 12:34:19 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')]} 12:34:19 INFO - https://queue.taskcluster.net/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:34:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 12:34:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 12:34:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 12:34:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 12:34:21 INFO - Downloaded 18102376 bytes. 12:34:21 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:34:21 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 12:34:25 INFO - Return code: 0 12:34:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:34:25 INFO - https://queue.taskcluster.net/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 12:34:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 12:34:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 12:34:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 12:34:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 12:34:29 INFO - Downloaded 10840145 bytes. 12:34:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:34:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 12:34:29 INFO - Return code: 0 12:34:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:34:29 INFO - https://queue.taskcluster.net/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 12:34:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 12:34:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 12:34:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 12:34:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 13:00:40 INFO - Downloaded 114098841 bytes. 13:00:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 13:00:40 INFO - mkdir: /builds/slave/test/properties 13:00:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:00:40 INFO - Writing to file /builds/slave/test/properties/build_url 13:00:40 INFO - Contents: 13:00:40 INFO - build_url:https://queue.taskcluster.net/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 13:00:42 INFO - Running post-action listener: _resource_record_post_action 13:00:42 INFO - Running post-action listener: set_extra_try_arguments 13:00:42 INFO - ##### 13:00:42 INFO - ##### Running populate-webroot step. 13:00:42 INFO - ##### 13:00:42 INFO - Running pre-action listener: _resource_record_pre_action 13:00:42 INFO - Running main action method: populate_webroot 13:00:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:00:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:00:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:00:42 INFO - '--tppagecycles', 13:00:42 INFO - '7'], 13:00:42 INFO - 'tests': ['tsvgm']}}, 13:00:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:00:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:00:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:00:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:00:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:00:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:00:42 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:00:42 INFO - 'talos_options': ['--disable-e10s'], 13:00:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:00:42 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:00:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:00:42 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:00:42 INFO - 'talos_options': ['--disable-e10s'], 13:00:42 INFO - 'tests': ['damp', 'tps']}, 13:00:42 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:00:42 INFO - 'tests': ['damp', 'tps']}, 13:00:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:00:42 INFO - 'tests': ['dromaeo_dom']}, 13:00:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:00:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:00:42 INFO - 'tests': ['a11yr', 13:00:42 INFO - 'ts_paint', 13:00:42 INFO - 'tpaint', 13:00:42 INFO - 'sessionrestore', 13:00:42 INFO - 'sessionrestore_no_auto_restore', 13:00:42 INFO - 'tabpaint']}, 13:00:42 INFO - 'other-e10s': {'tests': ['a11yr', 13:00:42 INFO - 'ts_paint', 13:00:42 INFO - 'tpaint', 13:00:42 INFO - 'sessionrestore', 13:00:42 INFO - 'sessionrestore_no_auto_restore', 13:00:42 INFO - 'tabpaint']}, 13:00:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:00:42 INFO - 'ts_paint', 13:00:42 INFO - 'tpaint', 13:00:42 INFO - 'sessionrestore', 13:00:42 INFO - 'sessionrestore_no_auto_restore', 13:00:42 INFO - 'tabpaint']}, 13:00:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:00:42 INFO - 'ts_paint', 13:00:42 INFO - 'tpaint', 13:00:42 INFO - 'sessionrestore', 13:00:42 INFO - 'sessionrestore_no_auto_restore', 13:00:42 INFO - 'tabpaint']}, 13:00:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:00:42 INFO - 'tests': ['a11yr', 13:00:42 INFO - 'ts_paint', 13:00:42 INFO - 'tpaint', 13:00:42 INFO - 'sessionrestore', 13:00:42 INFO - 'sessionrestore_no_auto_restore', 13:00:42 INFO - 'tabpaint']}, 13:00:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:00:42 INFO - 'tests': ['a11yr', 13:00:42 INFO - 'ts_paint', 13:00:42 INFO - 'tpaint', 13:00:42 INFO - 'sessionrestore', 13:00:42 INFO - 'sessionrestore_no_auto_restore', 13:00:42 INFO - 'tabpaint']}, 13:00:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:00:42 INFO - 'tests': ['tsvgx', 13:00:42 INFO - 'tsvgr_opacity', 13:00:42 INFO - 'tart', 13:00:42 INFO - 'tscrollx', 13:00:42 INFO - 'cart']}, 13:00:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:00:42 INFO - 'tsvgr_opacity', 13:00:42 INFO - 'tart', 13:00:42 INFO - 'tscrollx', 13:00:42 INFO - 'cart']}, 13:00:42 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:00:42 INFO - 'talos_options': ['--disable-e10s'], 13:00:42 INFO - 'tests': ['tp5o']}, 13:00:42 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:00:42 INFO - 'tests': ['tp5o']}, 13:00:42 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:00:42 INFO - 'talos_options': ['--disable-e10s', 13:00:42 INFO - '--xperf_path', 13:00:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:00:42 INFO - 'tests': ['tp5n']}, 13:00:42 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:00:42 INFO - 'talos_options': ['--xperf_path', 13:00:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:00:42 INFO - 'tests': ['tp5n']}}, 13:00:42 INFO - 'talos.zip': {'path': '', 13:00:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:00:42 INFO - Running post-action listener: _resource_record_post_action 13:00:42 INFO - ##### 13:00:42 INFO - ##### Running create-virtualenv step. 13:00:42 INFO - ##### 13:00:42 INFO - Running pre-action listener: _resource_record_pre_action 13:00:42 INFO - Running main action method: create_virtualenv 13:00:42 INFO - Creating virtualenv /builds/slave/test/build/venv 13:00:42 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 13:00:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:00:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:00:42 INFO - Using real prefix '/tools/python27' 13:00:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:00:43 INFO - Installing distribute.............................................................................................................................................................................................done. 13:00:46 INFO - Installing pip.................done. 13:00:46 INFO - Return code: 0 13:00:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:00:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:00:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:00:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:00:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:00:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b5c2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5d2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8898593850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b461e00>, '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 0x10b2cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b45cb20>, '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.nq8uSYTVM0/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.QnazZYSwtU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 13:00:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 13:00:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 13:00:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nq8uSYTVM0/Render', 13:00:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:00:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:00:46 INFO - 'HOME': '/Users/cltbld', 13:00:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:00:46 INFO - 'LOGNAME': 'cltbld', 13:00:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:46 INFO - 'MOZ_NO_REMOTE': '1', 13:00:46 INFO - 'NO_EM_RESTART': '1', 13:00:46 INFO - 'PAGER': '/bin/cat', 13:00:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:00:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:46 INFO - 'PWD': '/builds/slave/test', 13:00:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:00:46 INFO - 'SHELL': '/bin/bash', 13:00:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QnazZYSwtU/Listeners', 13:00:46 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 13:00:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:00:46 INFO - 'USER': 'cltbld', 13:00:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:00:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:00:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:46 INFO - 'XPC_FLAGS': '0x0', 13:00:46 INFO - 'XPC_SERVICE_NAME': '0', 13:00:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:00:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:00:46 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 13:00:46 INFO - Cleaning up... 13:00:46 INFO - Return code: 0 13:00:46 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 13:00:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:00:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:00:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:00:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:00:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b5c2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5d2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8898593850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b461e00>, '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 0x10b2cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b45cb20>, '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.nq8uSYTVM0/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.QnazZYSwtU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 13:00:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 13:00:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 13:00:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nq8uSYTVM0/Render', 13:00:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:00:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:00:46 INFO - 'HOME': '/Users/cltbld', 13:00:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:00:46 INFO - 'LOGNAME': 'cltbld', 13:00:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:46 INFO - 'MOZ_NO_REMOTE': '1', 13:00:46 INFO - 'NO_EM_RESTART': '1', 13:00:46 INFO - 'PAGER': '/bin/cat', 13:00:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:00:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:46 INFO - 'PWD': '/builds/slave/test', 13:00:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:00:46 INFO - 'SHELL': '/bin/bash', 13:00:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QnazZYSwtU/Listeners', 13:00:46 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 13:00:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:00:46 INFO - 'USER': 'cltbld', 13:00:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:00:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:00:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:46 INFO - 'XPC_FLAGS': '0x0', 13:00:46 INFO - 'XPC_SERVICE_NAME': '0', 13:00:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:00:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:00:46 INFO - Downloading/unpacking psutil>=0.7.1 13:00:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:00:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:00:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:00:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:00:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:00:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:00:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:00:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:00:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:00:50 INFO - Installing collected packages: psutil 13:00:50 INFO - Running setup.py install for psutil 13:00:50 INFO - building 'psutil._psutil_osx' extension 13:00:50 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 13:00:50 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 13:00:51 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 13:00:51 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 13:00:51 INFO - building 'psutil._psutil_posix' extension 13:00:51 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 13:00:51 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:00:51 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:00:51 INFO - ^ 13:00:51 INFO - 1 warning generated. 13:00:51 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 13:00:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:00:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:00:51 INFO - Successfully installed psutil 13:00:51 INFO - Cleaning up... 13:00:51 INFO - Return code: 0 13:00:51 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 13:00:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:00:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:00:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:00:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:00:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b5c2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5d2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8898593850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b461e00>, '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 0x10b2cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b45cb20>, '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.nq8uSYTVM0/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.QnazZYSwtU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 13:00:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 13:00:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 13:00:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nq8uSYTVM0/Render', 13:00:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:00:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:00:51 INFO - 'HOME': '/Users/cltbld', 13:00:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:00:51 INFO - 'LOGNAME': 'cltbld', 13:00:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:51 INFO - 'MOZ_NO_REMOTE': '1', 13:00:51 INFO - 'NO_EM_RESTART': '1', 13:00:51 INFO - 'PAGER': '/bin/cat', 13:00:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:00:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:51 INFO - 'PWD': '/builds/slave/test', 13:00:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:00:51 INFO - 'SHELL': '/bin/bash', 13:00:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QnazZYSwtU/Listeners', 13:00:51 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 13:00:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:00:51 INFO - 'USER': 'cltbld', 13:00:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:00:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:00:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:51 INFO - 'XPC_FLAGS': '0x0', 13:00:51 INFO - 'XPC_SERVICE_NAME': '0', 13:00:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:00:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:00:51 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 13:00:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:00:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:00:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:00:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:00:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:00:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:00:54 INFO - Downloading mozsystemmonitor-0.0.tar.gz 13:00:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:00:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 13:00:54 INFO - Installing collected packages: mozsystemmonitor 13:00:54 INFO - Running setup.py install for mozsystemmonitor 13:00:54 INFO - Successfully installed mozsystemmonitor 13:00:54 INFO - Cleaning up... 13:00:54 INFO - Return code: 0 13:00:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:00:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:00:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:00:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:00:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:00:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:54 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 0x10b5c2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5d2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8898593850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b461e00>, '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 0x10b2cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b45cb20>, '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.nq8uSYTVM0/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.QnazZYSwtU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 13:00:54 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 13:00:54 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 13:00:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nq8uSYTVM0/Render', 13:00:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:00:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:00:54 INFO - 'HOME': '/Users/cltbld', 13:00:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:00:54 INFO - 'LOGNAME': 'cltbld', 13:00:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:54 INFO - 'MOZ_NO_REMOTE': '1', 13:00:54 INFO - 'NO_EM_RESTART': '1', 13:00:54 INFO - 'PAGER': '/bin/cat', 13:00:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:00:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:54 INFO - 'PWD': '/builds/slave/test', 13:00:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:00:54 INFO - 'SHELL': '/bin/bash', 13:00:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QnazZYSwtU/Listeners', 13:00:54 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 13:00:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:00:54 INFO - 'USER': 'cltbld', 13:00:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:00:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:00:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:54 INFO - 'XPC_FLAGS': '0x0', 13:00:54 INFO - 'XPC_SERVICE_NAME': '0', 13:00:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:00:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:00:54 INFO - Downloading/unpacking blobuploader==1.2.4 13:00:54 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 13:00:54 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 13:00:54 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 13:00:54 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 13:00:54 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 13:00:54 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 13:00:57 INFO - Downloading blobuploader-1.2.4.tar.gz 13:00:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:00:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:00: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 13:00: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 13:00: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 13:00: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 13:00: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 13:00: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 13:00:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:00:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:00:58 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 13:00:58 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 13:00:58 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 13:00:58 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 13:00:58 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 13:00:58 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 13:00:58 INFO - Downloading docopt-0.6.1.tar.gz 13:00:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:00:58 INFO - Installing collected packages: blobuploader, requests, docopt 13:00:58 INFO - Running setup.py install for blobuploader 13:00:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:00:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:00:58 INFO - Running setup.py install for requests 13:00:59 INFO - Running setup.py install for docopt 13:00:59 INFO - Successfully installed blobuploader requests docopt 13:00:59 INFO - Cleaning up... 13:00:59 INFO - Return code: 0 13:00:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:00:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:00:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:00:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 13:00:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:00:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:00:59 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 0x10b5c2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5d2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8898593850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b461e00>, '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 0x10b2cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b45cb20>, '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.nq8uSYTVM0/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.QnazZYSwtU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 13:00:59 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 13:00:59 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 13:00:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nq8uSYTVM0/Render', 13:00:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:00:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:00:59 INFO - 'HOME': '/Users/cltbld', 13:00:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:00:59 INFO - 'LOGNAME': 'cltbld', 13:00:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:00:59 INFO - 'MOZ_NO_REMOTE': '1', 13:00:59 INFO - 'NO_EM_RESTART': '1', 13:00:59 INFO - 'PAGER': '/bin/cat', 13:00:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:00:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:00:59 INFO - 'PWD': '/builds/slave/test', 13:00:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:00:59 INFO - 'SHELL': '/bin/bash', 13:00:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QnazZYSwtU/Listeners', 13:00:59 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 13:00:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:00:59 INFO - 'USER': 'cltbld', 13:00:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:00:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:00:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:00:59 INFO - 'XPC_FLAGS': '0x0', 13:00:59 INFO - 'XPC_SERVICE_NAME': '0', 13:00:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:00:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:00:59 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-UY7cXz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:00:59 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-9G9KGv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:00:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:00:59 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-2rCCzE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:01:00 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-h1ZTxD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:01:00 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-0hVOyv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:01:00 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-hjp4T4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:01:00 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-utKTkB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:01:00 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-PzSuKw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:01:00 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-xFZOzl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:01:00 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-q_n8SN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:01:00 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-bfciah-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:01:00 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-qzP8T7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:01:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:01:00 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-YI7xbT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:01:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:01:01 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-X78NL4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:01:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:01:01 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-uD17Uc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:01:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:01:01 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-mR0NrQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:01:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:01:01 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-_bAPNP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:01:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:01:01 INFO - Running setup.py install for manifestparser 13:01:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:01:01 INFO - Running setup.py install for mozcrash 13:01:01 INFO - Running setup.py install for mozdebug 13:01:01 INFO - Running setup.py install for mozdevice 13:01:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:01:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:01:02 INFO - Running setup.py install for mozfile 13:01:02 INFO - Running setup.py install for mozhttpd 13:01:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:01:02 INFO - Running setup.py install for mozinfo 13:01:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:01:02 INFO - Running setup.py install for mozInstall 13:01:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:01:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:01:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:01:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:01:02 INFO - Running setup.py install for mozleak 13:01:02 INFO - Running setup.py install for mozlog 13:01:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:01:02 INFO - Running setup.py install for moznetwork 13:01:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:01:03 INFO - Running setup.py install for mozprocess 13:01:03 INFO - Running setup.py install for mozprofile 13:01:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:01:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:01:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:01:03 INFO - Running setup.py install for mozrunner 13:01:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:01:03 INFO - Running setup.py install for mozscreenshot 13:01:03 INFO - Running setup.py install for moztest 13:01:03 INFO - Running setup.py install for mozversion 13:01:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:01:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:01:04 INFO - Cleaning up... 13:01:04 INFO - Return code: 0 13:01:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:01:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:01:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:01:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:01:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:01:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:01:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:01:04 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 0x10b5c2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5d2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8898593850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b461e00>, '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 0x10b2cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b45cb20>, '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.nq8uSYTVM0/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.QnazZYSwtU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 13:01:04 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 13:01:04 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 13:01:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nq8uSYTVM0/Render', 13:01:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:01:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:01:04 INFO - 'HOME': '/Users/cltbld', 13:01:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:01:04 INFO - 'LOGNAME': 'cltbld', 13:01:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:01:04 INFO - 'MOZ_NO_REMOTE': '1', 13:01:04 INFO - 'NO_EM_RESTART': '1', 13:01:04 INFO - 'PAGER': '/bin/cat', 13:01:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:01:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:01:04 INFO - 'PWD': '/builds/slave/test', 13:01:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:01:04 INFO - 'SHELL': '/bin/bash', 13:01:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QnazZYSwtU/Listeners', 13:01:04 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 13:01:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:01:04 INFO - 'USER': 'cltbld', 13:01:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:01:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:01:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:01:04 INFO - 'XPC_FLAGS': '0x0', 13:01:04 INFO - 'XPC_SERVICE_NAME': '0', 13:01:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:01:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:01:04 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-Ay8tea-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:01:04 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)) 13:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:01:04 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-D0Bc1U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:01:04 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)) 13:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:01:04 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-QNtE30-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:01:04 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)) 13:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:01:04 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-pTNFd6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:01:04 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)) 13:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:01:04 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-Cbj2cs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:01:04 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)) 13:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:01:04 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-m0MVTK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:01:04 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)) 13:01:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:01:04 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-CEZWkd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:01:05 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)) 13:01:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:01:05 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-8noBT_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:01:05 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)) 13:01:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:01:05 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-uaVIRB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:01:05 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)) 13:01:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:01:05 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-TixfZX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:01:05 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)) 13:01:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:01:05 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-eFxbEL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:01:05 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)) 13:01:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:01:05 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-uo5UN_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:01:05 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)) 13:01:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:01:05 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-KAZ0mH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:01:05 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)) 13:01:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:01:05 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-z7f17y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:01:05 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)) 13:01:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:01:05 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-s9uiEG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:01:05 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)) 13:01:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:01:05 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-tnY5R_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:01:06 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)) 13:01:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:01:06 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-dGHzZz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:01:06 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)) 13:01:06 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)) 13:01:06 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)) 13:01:06 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)) 13:01:06 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)) 13:01:06 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)) 13:01:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:01:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:01:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:01:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:01:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:01:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:01:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:01:08 INFO - Downloading blessings-1.6.tar.gz 13:01:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:01:08 INFO - Installing collected packages: blessings 13:01:08 INFO - Running setup.py install for blessings 13:01:08 INFO - Successfully installed blessings 13:01:08 INFO - Cleaning up... 13:01:08 INFO - Return code: 0 13:01:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:01:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:01:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:01:09 INFO - Reading from file tmpfile_stdout 13:01:09 INFO - Current package versions: 13:01:09 INFO - blessings == 1.6 13:01:09 INFO - blobuploader == 1.2.4 13:01:09 INFO - docopt == 0.6.1 13:01:09 INFO - manifestparser == 1.1 13:01:09 INFO - mozInstall == 1.12 13:01:09 INFO - mozcrash == 0.17 13:01:09 INFO - mozdebug == 0.1 13:01:09 INFO - mozdevice == 0.48 13:01:09 INFO - mozfile == 1.2 13:01:09 INFO - mozhttpd == 0.7 13:01:09 INFO - mozinfo == 0.9 13:01:09 INFO - mozleak == 0.1 13:01:09 INFO - mozlog == 3.1 13:01:09 INFO - moznetwork == 0.27 13:01:09 INFO - mozprocess == 0.22 13:01:09 INFO - mozprofile == 0.28 13:01:09 INFO - mozrunner == 6.11 13:01:09 INFO - mozscreenshot == 0.1 13:01:09 INFO - mozsystemmonitor == 0.0 13:01:09 INFO - moztest == 0.7 13:01:09 INFO - mozversion == 1.4 13:01:09 INFO - psutil == 3.1.1 13:01:09 INFO - requests == 1.2.3 13:01:09 INFO - wsgiref == 0.1.2 13:01:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:01:09 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')]} 13:01:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:01:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:01:09 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')]} 13:01:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:01:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:01:09 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 0x10b5c2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5d2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8898593850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b461e00>, '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 0x10b2cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b45cb20>, '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.nq8uSYTVM0/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.QnazZYSwtU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 13:01:09 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 13:01:09 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 13:01:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nq8uSYTVM0/Render', 13:01:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:01:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:01:09 INFO - 'HOME': '/Users/cltbld', 13:01:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:01:09 INFO - 'LOGNAME': 'cltbld', 13:01:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:01:09 INFO - 'MOZ_NO_REMOTE': '1', 13:01:09 INFO - 'NO_EM_RESTART': '1', 13:01:09 INFO - 'PAGER': '/bin/cat', 13:01:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:01:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:01:09 INFO - 'PWD': '/builds/slave/test', 13:01:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:01:09 INFO - 'SHELL': '/bin/bash', 13:01:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QnazZYSwtU/Listeners', 13:01:09 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 13:01:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:01:09 INFO - 'USER': 'cltbld', 13:01:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:01:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:01:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:01:09 INFO - 'XPC_FLAGS': '0x0', 13:01:09 INFO - 'XPC_SERVICE_NAME': '0', 13:01:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:01:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:01:09 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)) 13:01:09 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)) 13:01:09 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)) 13:01:09 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)) 13:01:09 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)) 13:01:09 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)) 13:01:09 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)) 13:01:09 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)) 13:01:09 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)) 13:01:09 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)) 13:01:09 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)) 13:01:09 INFO - Cleaning up... 13:01:09 INFO - Return code: 0 13:01:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:01:09 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')]} 13:01:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:01:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:01:09 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')]} 13:01:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:01:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:01:09 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 0x10b5c2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5d2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8898593850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b461e00>, '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 0x10b2cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b45cb20>, '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.nq8uSYTVM0/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.QnazZYSwtU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 13:01:09 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 13:01:09 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 13:01:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nq8uSYTVM0/Render', 13:01:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:01:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:01:09 INFO - 'HOME': '/Users/cltbld', 13:01:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:01:09 INFO - 'LOGNAME': 'cltbld', 13:01:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:01:09 INFO - 'MOZ_NO_REMOTE': '1', 13:01:09 INFO - 'NO_EM_RESTART': '1', 13:01:09 INFO - 'PAGER': '/bin/cat', 13:01:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:01:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:01:09 INFO - 'PWD': '/builds/slave/test', 13:01:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:01:09 INFO - 'SHELL': '/bin/bash', 13:01:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QnazZYSwtU/Listeners', 13:01:09 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 13:01:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:01:09 INFO - 'USER': 'cltbld', 13:01:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:01:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:01:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:01:09 INFO - 'XPC_FLAGS': '0x0', 13:01:09 INFO - 'XPC_SERVICE_NAME': '0', 13:01:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:01:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:01:09 INFO - Downloading/unpacking jsonschema 13:01:09 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 13:01:09 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 13:01:09 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 13:01:09 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 13:01:09 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 13:01:09 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 13:01:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:01:12 INFO - Downloading/unpacking functools32 (from jsonschema) 13:01:12 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 13:01:12 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 13:01:12 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 13:01:12 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 13:01:12 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 13:01:12 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 13:01:12 INFO - Downloading functools32-3.2.3-2.tar.gz 13:01:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:01:12 INFO - warning: no files found matching '*.txt' 13:01:12 INFO - no previously-included directories found matching 'build' 13:01:12 INFO - no previously-included directories found matching 'dist' 13:01:12 INFO - no previously-included directories found matching '.git*' 13:01:12 INFO - Installing collected packages: jsonschema, functools32 13:01:12 INFO - Running setup.py install for functools32 13:01:12 INFO - warning: no files found matching '*.txt' 13:01:12 INFO - no previously-included directories found matching 'build' 13:01:12 INFO - no previously-included directories found matching 'dist' 13:01:12 INFO - no previously-included directories found matching '.git*' 13:01:12 INFO - Successfully installed jsonschema functools32 13:01:12 INFO - Cleaning up... 13:01:12 INFO - Return code: 0 13:01:12 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 13:01: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')]} 13:01:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:01:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:01: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')]} 13:01:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:01:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:01:12 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 0x10b5c2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5d2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8898593850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b461e00>, '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 0x10b2cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b45cb20>, '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.nq8uSYTVM0/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.QnazZYSwtU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 13:01:12 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 13:01:12 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 13:01:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nq8uSYTVM0/Render', 13:01:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:01:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:01:12 INFO - 'HOME': '/Users/cltbld', 13:01:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:01:12 INFO - 'LOGNAME': 'cltbld', 13:01:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:01:12 INFO - 'MOZ_NO_REMOTE': '1', 13:01:12 INFO - 'NO_EM_RESTART': '1', 13:01:12 INFO - 'PAGER': '/bin/cat', 13:01:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:01:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:01:12 INFO - 'PWD': '/builds/slave/test', 13:01:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:01:12 INFO - 'SHELL': '/bin/bash', 13:01:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QnazZYSwtU/Listeners', 13:01:12 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 13:01:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:01:12 INFO - 'USER': 'cltbld', 13:01:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:01:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:01:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:01:12 INFO - 'XPC_FLAGS': '0x0', 13:01:12 INFO - 'XPC_SERVICE_NAME': '0', 13:01:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:01:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:01:13 INFO - Downloading/unpacking flake8 13:01:13 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 13:01:13 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 13:01:13 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 13:01:13 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 13:01:13 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 13:01:13 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 13:01:15 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 13:01:15 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 13:01:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:01:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:01:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:01:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:01:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:01:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:01:15 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 13:01:15 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 13:01:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:01:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:01:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:01:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:01:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:01:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:01:16 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 13:01:16 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 13:01:16 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 13:01:16 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 13:01:16 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 13:01:16 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 13:01:16 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 13:01:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 13:01:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:01:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:01:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:01:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:01:16 INFO - no previously-included directories found matching 'docs/_build' 13:01:16 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 13:01:16 INFO - Running setup.py install for pep8 13:01:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:01:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:01:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:01:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:01:17 INFO - no previously-included directories found matching 'docs/_build' 13:01:17 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 13:01:17 INFO - Successfully installed flake8 mccabe pyflakes pep8 13:01:17 INFO - Cleaning up... 13:01:17 INFO - Return code: 0 13:01:17 INFO - Running post-action listener: _resource_record_post_action 13:01:17 INFO - Running post-action listener: _start_resource_monitoring 13:01:17 INFO - Starting resource monitoring. 13:01:17 INFO - ##### 13:01:17 INFO - ##### Running install step. 13:01:17 INFO - ##### 13:01:17 INFO - Running pre-action listener: _resource_record_pre_action 13:01:17 INFO - Running main action method: install 13:01:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:01:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:01:17 INFO - Reading from file tmpfile_stdout 13:01:17 INFO - Detecting whether we're running mozinstall >=1.0... 13:01:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:01:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:01:17 INFO - Reading from file tmpfile_stdout 13:01:17 INFO - Output received: 13:01:17 INFO - Usage: mozinstall [options] installer 13:01:17 INFO - Options: 13:01:17 INFO - -h, --help show this help message and exit 13:01:17 INFO - -d DEST, --destination=DEST 13:01:17 INFO - Directory to install application into. [default: 13:01:17 INFO - "/builds/slave/test"] 13:01:17 INFO - --app=APP Application being installed. [default: firefox] 13:01:17 INFO - mkdir: /builds/slave/test/build/application 13:01:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 13:01:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 13:01:49 INFO - Reading from file tmpfile_stdout 13:01:49 INFO - Output received: 13:01:49 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 13:01:49 INFO - Running post-action listener: _resource_record_post_action 13:01:49 INFO - ##### 13:01:49 INFO - ##### Running run-tests step. 13:01:49 INFO - ##### 13:01:49 INFO - Running pre-action listener: _resource_record_pre_action 13:01:49 INFO - Running main action method: run_tests 13:01:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:01:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:01:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:01:49 INFO - 2016-05-19 13:01:49.280 screenresolution[1643:8877] starting screenresolution argv=screenresolution get 13:01:49 INFO - 2016-05-19 13:01:49.283 screenresolution[1643:8877] Display 0: 1600x1200x32@60 13:01:49 INFO - 2016-05-19 13:01:49.289 screenresolution[1644:8880] starting screenresolution argv=screenresolution list 13:01:49 INFO - Available Modes on Display 0 13:01:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:01:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:01:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:01:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:01:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:01:49 INFO - Intel Iris: 13:01:49 INFO - Chipset Model: Intel Iris 13:01:49 INFO - Type: GPU 13:01:49 INFO - Bus: Built-In 13:01:49 INFO - VRAM (Dynamic, Max): 1536 MB 13:01:49 INFO - Vendor: Intel (0x8086) 13:01:49 INFO - Device ID: 0x0a2e 13:01:49 INFO - Revision ID: 0x0009 13:01:49 INFO - Displays: 13:01:49 INFO - Display: 13:01:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:01:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:01:49 INFO - Display Serial Number: 1600x1200 60 13:01:49 INFO - Main Display: Yes 13:01:49 INFO - Mirror: Off 13:01:49 INFO - Online: Yes 13:01:49 INFO - Rotation: Supported 13:01:49 INFO - Return code: 0 13:01:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:01:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:01:49 INFO - Python 2.7.3 13:01:49 INFO - Return code: 0 13:01:49 INFO - grabbing minidump binary from tooltool 13:01:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:01:49 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 0x10b461e00>, '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 0x10b2cadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b45cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:01:49 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 13:01:49 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 13:01:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:01:49 INFO - Return code: 0 13:01:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:01:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:01:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:01:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:01:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:01:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 13:01:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 13:01:50 INFO - Return code: 0 13:01:50 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-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0195', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 13:01:50 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-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0195 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 13:01:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nq8uSYTVM0/Render', 13:01:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:01:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:01:50 INFO - 'HOME': '/Users/cltbld', 13:01:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:01:50 INFO - 'LOGNAME': 'cltbld', 13:01:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:01:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:01:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:01:50 INFO - 'MOZ_NO_REMOTE': '1', 13:01:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:01:50 INFO - 'NO_EM_RESTART': '1', 13:01:50 INFO - 'PAGER': '/bin/cat', 13:01:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:01:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:01:50 INFO - 'PWD': '/builds/slave/test', 13:01:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:01:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:01:50 INFO - 'SHELL': '/bin/bash', 13:01:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QnazZYSwtU/Listeners', 13:01:50 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 13:01:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:01:50 INFO - 'USER': 'cltbld', 13:01:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:01:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:01:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:01:50 INFO - 'XPC_FLAGS': '0x0', 13:01:50 INFO - 'XPC_SERVICE_NAME': '0', 13:01:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:01:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0195', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 13:01:50 INFO - mozversion application_buildid: 20160519112644 13:01:50 INFO - mozversion application_changeset: 133e9db008eadefc4426a3731d30f60f0e290a76 13:01:50 INFO - mozversion application_display_name: FirefoxDeveloperEdition 13:01:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:01:50 INFO - mozversion application_name: Firefox 13:01:50 INFO - mozversion application_remotingname: firefox-dev 13:01:50 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:01:50 INFO - mozversion application_vendor: Mozilla 13:01:50 INFO - mozversion application_version: 48.0a2 13:01:50 INFO - mozversion platform_buildid: 20160519112644 13:01:50 INFO - mozversion platform_changeset: 133e9db008eadefc4426a3731d30f60f0e290a76 13:01:50 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:01:50 INFO - mozversion platform_version: 48.0a2 13:01:50 INFO - using testdate: 1463688110 13:01:50 INFO - actual date: 1463688110 13:01:50 INFO - starting webserver on 'localhost:49275' 13:01:50 INFO - SUITE-START | Running 2 tests 13:01:50 INFO - TEST-START | dromaeo_css 13:01:50 INFO - operating with platform_type : mac_ 13:01:50 INFO - Initialising browser for dromaeo_css test... 13:01:50 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/tmpG57rpB/profile http://localhost:49275/getInfo.html) 13:01:53 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 13:01:53 INFO - PROCESS | 1662 | colorDepth:24 13:01:53 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 13:01:53 INFO - PROCESS | 1662 | __metrics 13:01:53 ERROR - PROCESS | 1662 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 13:01:54 INFO - TEST-INFO | 1662: exit 0 13:01:54 INFO - Browser initialized. 13:01:54 INFO - Running cycle 1/1 for dromaeo_css test... 13:01:54 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/tmpG57rpB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:02:06 INFO - PROCESS | 1666 | RSS: Main: 221229056 13:02:06 INFO - PROCESS | 1666 | 13:02:55 INFO - PROCESS | 1666 | 1463688175868 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] 13:05:06 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 13:05:08 INFO - PROCESS | 1666 | RSS: Main: 232996864 13:05:08 INFO - PROCESS | 1666 | 13:08:10 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 13:08:10 INFO - PROCESS | 1666 | RSS: Main: 243499008 13:08:10 INFO - PROCESS | 1666 | 13:11:12 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 13:11:13 INFO - PROCESS | 1666 | RSS: Main: 245272576 13:11:13 INFO - PROCESS | 1666 | 13:14:14 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 13:14:15 INFO - PROCESS | 1666 | RSS: Main: 247914496 13:14:15 INFO - PROCESS | 1666 | 13:17:18 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 13:17:18 INFO - PROCESS | 1666 | RSS: Main: 249991168 13:17:18 INFO - PROCESS | 1666 | 13:20:20 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 13:20:21 INFO - PROCESS | 1666 | RSS: Main: 251142144 13:20:21 INFO - PROCESS | 1666 | 13:20:21 INFO - PROCESS | 1666 | __start_tp_report 13:20:21 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 13:20:21 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 13:20:21 INFO - PROCESS | 1666 | |i|pagename|runs| 13:20:21 INFO - PROCESS | 1666 | |0;dojo.html;3399.5580574525306;3425.5033020212086;3460;3501.842417091231;3518.7156687808942;5676.829695109148;5716.914246286309;5733.48398644122;5744.368119506851;5749.051406517925;4724.433068031833;5753.338366087901;5775.2203452533895;5795.130730390443;5820.272465941757;4810.374651295327;5161.81933632322;5194.480551944813;5228.215767634866;5238.68567885928;4253.447051883266;4982.626303027288;5046.54581087702;5133.845984620468;5349.598780091489;5226.216067589873;5235.057689615867;5289;5299.231611416342;5320.17537281722;5236.973815130938;5241.685498870061;5264.52619264268;5270.104082305978;5298.802407102122;5080.364954380702;5131.461196574343;5176.456472070416;5225.555827754645;5251.842444726664;5579.995600791855;5582.720863956786;5629.155626656035;5666.688332141731;5673.489385955242;6158.9692051539905;6162.26052873658;6215.316315205324;6216.347283535254;6260.280067792217;6149.815505534841;6230.781922632731;6296.6536840473755;6307.684615769193;6339.36606339367;4820;5843.3864444233595;5845.1816745655215;5848.3274423441;5877.823665290048;6314.62112273265;6319.24169099711;6328.303886572473;6330;6332.936670633327;6041.969790151109;6135.202423684982;6156.630602163885;6157.415045570664;6195.628262304276;6296.8425789354715;6306.59007164542;6312.5265605078885;6321;6339.619622822645;4789.712617242976;5165.560927321145;5203.60972927032;6090.69050154219;6298.937010629927;6141.946315431794;6214.1921550198185;6265.373462653789;6286.151369565142;6347.714352854133;4882.365292512021;4948.554630083309;4961.677490963076;4969.875753106129;4987.002599480118;4473.977630111829;4507.166174257773;4507.661925355612;4522.683412161117;4532.456105267323;4693.7653117344;4747.454042785055;5693.544516438675;5795.507381872504;5808.564357673107;6279.686015699288;6296.118543403835;6297.779577714758;6297.874042519124;6322.114903913456;5803.303603567514;5828.329742079715;5843.678597677169;5851.239338885917;5881.588288819741;5339.81310654133;5408.891822163535;5455.34535855692;5490.890182196334;5501.202325662756;6371.681415929278;6425.839354016187;6439.484841212692;6457.515686323544;6459.870802583922;6191.071339299141;6321.1782468278825;6321.304656487875;6322.747090116344;6325.873482530232;6336.9049464259;6385.45723613498;6390.201224846895;6406.423421892052;6431.871362572629;6191.4118158774845;6244.906326405198;6245.9063114052415;6247.68761561911;6255.343188965093;6182.752689892354;6204.069389591597;6336.809895703136;6338.841528961812;6348.682565871595;5250.18622113573;6084.421979911901;6118.345080237391;6181.134641150333;6209.348018458178;6149.600275982047;6165.691715414122;6177.598456100339;6180.845478862884;6183.196184495987;4617.492075871719;5778.959787238337;5784;5946.583739138218;6014.097885317237;6133.793750436688;6140.508759299156;6149.446549810538;6151.784687535915;6176.907346389898;6040.788572399945;6046.365131661112;6052.0921861719335;6063.5452341073715;6065 13:20:21 INFO - PROCESS | 1666 | |1;ext.html;519.2159838643648;531.7979167916192;539.8380485854243;540.7701726766121;575.272280565085;26974.055908043247;31843.203919902015;32002.679973200262;33358.833205833966;39512.20975580487;41178;41844;41893.79053104738;41916.58083419165;41983.58016419835;8226.835463290761;9486.193673537757;9675.080867317594;9727.951360243189;9735.075167859077;8450.450720703166;8686.914135733034;9496.050394960517;9851.064148905842;9881.555330010167;9795.657151999681;9827.459489728062;9873.950630246838;9874.555644995957;9896.15882649972;8338.749837504854;9842.212622990144;9856.50717464124;9860.161886239679;9861.654842080492;9690.32167748258;9723.416595004323;9740.318177727562;9756.414615123116;9795.314327997043;31814;40015.39976900349;40315;40386.59613403858;40405;2778.4443111377805;3085;3340.4822252550894;3436.553248077759;3749.362608356586;26847.32881677954;27016;27117.45765084707;27151.45697086067;27194.864025679744;2667.8799454024615;2675.531781938153;2720.5239083160563;2745.437185377013;2772.88908443662;319992.8000719986;363644.18177909206;393712;394097.99999999715;395996;35669.64330356715;35958;36784.26431471355;37236.25527489435;37425.43861842075;36145.27709445796;36249.81875090607;36483.81758091193;36604.816975915484;36736;15761.448349307719;15780.68438631221;15957.840421595867;16129.596760081093;16137.919310403373;13170.275634840176;13197.604071877859;13232.933835330761;13258.668533286746;13453.798193027113;8050.798730031679;8112.553809540532;8267.214614611603;8399.454035487674;8493.915060849313;2841.6305880235436;2933.5013047781927;3445.0526105320737;3458.187325978393;3481.094915321984;958.4632605740808;971.121135372489;1024.9641262555774;1056.0917610854658;1136.7215032317135;28968.275793105342;29473.705262947096;29620.40759184804;29737.40525189484;30251.88236823401;953.5327689432268;1137.2778285788584;1139.9968028135188;1145.5933143733855;1209.2683926224684;15328.616784580474;15617.98483098595;15950.76073858893;16742.162891855358;17079.573010674583;605587;982924;1006025;1015979;1020079;64128;64307.35692643107;65101.67449162724;65163;65187.044388668415;1510.4788847847476;1516.105497756329;1523.6190952261713;1524.7484165112633;1531.4563330017907;30295.848520757256;31527.369452611278;31602.683973159976;32107.83946080255;32284.35431291408;56499.43500565106;57279.42720572741;58193.12710309433;58264.70867645634;58285.708571456875;486558;553696;566279.0116296518;590407;591227;27878.3030424241;29078.27304317409;29316.853415732785;29331.12006639804;29458.705412945597;40823.79588102041;41280.38079428871;45524.77237613791;45950.85122871955;48307.51692483171;39473.40789888096;39829.40255896106;40403.393949091376;40716.18567628571;41096;37924.62075379321;38832.80583597064;39216;39511.80244098761;39770.80114599408;31572.36855262836;38448.80775596104;39491.605083949944;39559;40021;33223.335533288715;34588.65411345854;35149.824250878584;35327.82336088406;35411.291774164885;25967.091151809596;26001.219963401127;26116.955321786914;26126.477470450103;26481.60277595876 13:20:21 INFO - PROCESS | 1666 | |2;jquery.html;371.1667306896018;394.6665068017526;396.66283658889927;404.854252469111;407.9163771426858;20265.290714824983;20312.593748125048;20846.583068338623;20902.89548552257;21353.2631789437;17775.644487110283;20760.896195519003;21060.473488162803;21070.367888963316;21906.78093219067;4470.53059428761;5791.971040144815;6031.668258245817;6064.514838812906;6071.605345652541;5143.254228136918;5901.4688678018965;6000.18997435345;6014.699265036731;6035.879282414349;5698.145278208261;5901.3803550627;5954.553408494358;5984.670843103628;6012;5886.6468011919205;5931.169636250928;5942.8811423771285;5950.97024514879;5957.821265362024;5127.359080114986;5207.531322181022;5212.113940630102;5485.670859748388;5833.883322333572;13121.147125436815;13339.399727012307;13350.465981360734;13359.265240411774;21750.564988700295;2079.2099002379;2166.869987800737;2206.4042708468623;2241.002753774571;2264.1509433962265;9587.376820506644;9623.1820295275;10087.092161705408;10119.544620492095;12623.558175463815;1673.8911970721865;1725.1546742096407;1776.6979613465612;1806.3980304625763;1830.103249407794;120051.39974300073;122361.38819305846;124482;128196;128453.71546284511;11477.311361318347;11627.302361858146;11923.403829808474;12014.219075760047;12105.515779368727;14828.961972662026;14836.258187090818;14892.404303827941;14922.253887305591;14922.880783941248;8996.190342869042;9021.32340074484;9037.322200834964;9106.772330691652;9122.36143469964;9000.18998290144;9051.366404351764;9159.175674189355;9174.220191283815;9236.445813251226;3922.921541569153;4593.333966574828;4620.722756634613;4685.821093191682;4707.835225767081;1973.082516629774;2012.9899350503154;2019.3538067818452;2019.6263691217173;2023.3828682252054;877.7849426890452;968.7239136846106;985.393982700634;986.0730912941812;1013.4729940430934;19307.8069219306;20513.589728205352;20546.794532054788;20653.89673051625;20682.793172068086;948.5162567090833;964.7346979580672;976.1507488485064;1008.8839783424853;1028.93311934724;10859.457027148612;11000.889991099986;11007;11017.944910275557;11039.613613523488;387859;591158;591773;612406;614081;56425;72628.27371726216;89170.55414722885;125036;131404;1016.3596933931724;1017.1710056304237;1051.4112097225425;1057.3444464432102;1070.9410982395887;17949.371771987917;18247.45257642273;18291.359802406852;18447.907760461112;19034.333798317548;40049;51171.74414127906;51357.48642513676;54134;54565.45434545763;69889;73011.63494182494;73016.63491682507;73444;73931.89102163579;7814.374850012099;7980;8029;8133.308668763221;8380.16198380157;10941.507632156412;12322.01423886105;12568.93715531453;12667.556635517712;12852.293123878277;9442.81114377695;10877.18421118435;10883.619073332395;11025.83461248098;11193.72015699614;10765.30025548337;10802;10849.511771970438;10983.450827458435;11099.44502774842;9334;10798.56805727762;11263.887361126284;11420.94289528547;11534.711632209262;8228.50628962264;8271.172882711922;8316;8413.411061225655;8578.78553036179;9599.904000960181;11079.113670906469;11096.889031109586;11248.987591116838;11287.43562821872 13:20:21 INFO - PROCESS | 1666 | |3;mootools.html;1618.5387164658073;1633.1017940132924;1877.7840548336937;1926.8169523895233;1988.492934301753;7881.5271083735015;8396.328293736502;8507.447015943959;8550.871736923944;8554.401191916568;8523.616437260323;8562.229399354057;8570.485770853753;8682.86975695365;8705.347098967572;2299.091858715806;2406.4946361264156;2412.5416170927556;2413;2447.571674956884;2407.626817843237;2418.613021916494;2430.3559556717482;2433.1605596069326;2459.6187590923346;2419.6249581314923;2424.442378253003;2444.963325550118;2451.5832308507597;2469.259222233332;2409.72288186859;2433.3551608823677;2446.669699590559;2447.486027934136;2454.6563481112657;2394.6767186429784;2405.2423486601665;2419.250032489916;2437.683101196833;2440.341107900857;8759.737207883774;8794.648214071429;8795.252403545706;8818.426802257833;8859.822803543893;5344.465553444663;5347.1177255752755;5398.028354896117;5415.918761218585;5422.213779002062;3477.1828620274214;3526.1360966563097;3545.0251180925193;3551.5027896094566;3595.964040359589;809.7975506123469;866.3026263857593;1022.0801278849021;1039.859618951447;1054.2462139570243;11663.008644265248;11791.351475668835;11792.115591330685;11794.528218871235;11810.704732381673;4764.499727528629;4806.182948898696;4838.250071238964;4862.17343051682;4870.4155501340065;8019;9981.351212171186;10013.09882110599;10145.137663298554;10145.9492702536;5332.493413125747;5376.892462150813;5430.212619170277;5431.619786614899;5469.644473109235;4399.098184872094;4761.785719642625;4774.904501909942;4875.07373599015;4957.082939656103;4023.5574086850424;4032.495938007749;4124.1957818225155;4127.607877251657;4143.585641435832;1352.790317500789;1364.9181049137082;1366.5217173989224;1370.6025252676811;1372.0121512510975;898.4761724716153;904.900460949295;925.8703781470599;935.3078721745995;952.9666461673808;5482.23248745176;5771.913421298684;5957.4042595740075;5979.940200597938;5994.6403215807195;930.3208657679932;942.1332374215738;996.3274789517047;1025.123519390925;1027.2603725317758;3727.142757165867;3926.8036598170547;3963.504561929759;3986.740861843971;4016.2770701273466;10524.473776311153;10794.136469082612;10889.945550272356;10936.835947460795;10946.343219406861;9958.850617240747;10348;10672.46637668129;10749.978752018545;10778.245522813484;8311.542865142475;8345.082040975487;8371.16288371158;8433.662653493791;8537.658493660185;5535.308086489189;5579.832605021856;5622.678670512344;5649.37856835756;5674.650718366659;9375.953120234355;9616.807663846825;9686;9691.757706057406;9722.465264410524;9426.481543515172;9919.801603968024;9973.800523989625;9980.650677226262;9996.500174991366;5393.9730301348245;6370.8088757337355;6495.2855185928565;7020.122484689414;7119.501634885508;8127.512349259064;8137.633806478604;8196.303314218358;8201.26188643025;8203.30271926869;6915.377616014583;7138.428925686037;7293.781186564412;7318.048653674989;7441.665125069277;6746.696398661974;7165.641717913979;7176.354128128493;7233.421326293778;7283.798173301558;6838.555493892881;6841.794746157623;6906.723731050702;6956.721731130698;6981.022656827947;4825.155597770306;4829.661923665451;4881.633877459134;4981.402231732215;5020;4235.830566777295;4959.62802789785;4985.551300382838;5029.145045342374;5073.568746656576 13:20:21 INFO - PROCESS | 1666 | |4;prototype.html;311.5762562914436;315.76002238298895;325.76056598400186;327.7656475619932;330.65776920886873;6644.767433139841;6744.561603495782;6825.351591598796;6895.138107736533;6979.755708550202;6822.624755638451;6853.623050732208;6872.79381618551;6875.518713690044;6884.242733299333;4950.851474455772;4957.5786058185095;4983.476734942833;4985.501449855022;5019.723915184663;4986.4265609455015;4989.65072444929;4997.600191984632;5009.2486127080865;5020.723860187688;4229.069604686964;4272.807723652428;4931.988942266845;4936.950630493685;4972.627052971024;4630.073985202973;4821.132196204681;4936.679115857476;4942.209246520575;4963.503649635044;4060.918781624351;4061.5329237137516;4071.7760523171214;4079.77561234132;4082.8775136745944;6394.552381333308;6466.418022378009;6510.348965103499;6520.771772987922;6556.737730490822;5741.540676745851;5752.2234498342605;5765.106408506688;5778.27771528559;5787.13193021046;7214.603196824172;7223.963880180618;7224.458165637546;7246;7251.963740181265;3772.1889793694486;3803.8858834235016;4425.734455932655;4626.097910907374;4629.328747331618;7501.437392195553;7634.312911837961;7667.581497422994;7695.576743279117;7744.457887947902;3689.4281386385155;4699.647526435463;4705.776498110516;4706.811727530929;4707.129181101507;4771.379720636364;4779.545943135397;4783.378160839069;4796.760161991887;4807.855764327076;5735.713214339266;5740.741666625012;5754.827355179351;5756.237006236946;5774.566907481955;7026.613536255452;7284.125904891447;7294.963525182447;7342.412606991428;7361.6319184040585;1348.447136673959;1642.9238848554216;1663.5092647668966;1666.7083260429376;1670.991645041767;982.2731178927543;1034;1041.156663102889;1058.730023843915;1059.4861492176287;6674.733010679615;6767.2894346093935;6801.18385793708;6869.553479023847;6910;7603.581803000778;7605;7615.771526854203;7631.503952243197;7665.891879733006;6152.354002829638;6818.8636227276165;6824.727010919508;6826.146731658543;6877.965610171917;3420.589529256505;3903.8243279051944;3953.4069889516804;3997.960020399817;4057.4522439470293;8032.518048917083;8054.556999364974;8064.193580642006;8076.111627720946;8079.434439589172;7934;7937.126916039231;7977.481463704957;7992.440529163019;8023.756317770755;6951.65241737911;6954.374106330454;7529.322360987538;7609.581473019037;8005.599720013976;7382.630868456663;7660.233976602295;7675.961620191863;7688.961555192188;7702.383809295131;7513.248675132443;7532.246775322424;7541.20817314196;7564.357029652541;7585;7261.927380726125;8015.799605009922;8026.719064832703;8040.55776932251;8058.435909486514;4132.925439385721;4220.957790422057;4442.555744425532;4675.6259499239295;4687.859364219079;5417.079096553674;7269.890951635624;7431.925680743339;7564.432667550066;7667.086507127256;7333.7066517340845;7347.595882226529;7370.742024029133;7377.668004939684;7398.704051838082;6084.783043391251;7214.062171917617;7305.963470182615;7309.634518274172;7345.55926644403;7112.786616401516;7123.145222573325;7130.928690713234;7143.392811610864;7168.928310717035;6011.248593925759;7250.38371738387;7303.342699157101;7340.816479588053;7359.595222262827;6954.513184077268;7093.113360829896;7232.385247253832;7237.601931893796;7257.419406447577 13:20:21 INFO - PROCESS | 1666 | |5;yui.html;646.9971544106634;698.556416675411;749.6439191384093;756.1757684124303;756.4591317208195;14869.479568215102;14890.478833240839;14943;14987.700245995073;15031.699366012674;13829.79255311171;13860.237686927216;13875;13884.375203115858;13904.304784760747;714.1679942866555;907.9591418386156;914.1772404835634;929.9907000930006;931.8089791592741;906.1391677905983;908.150878928201;922.8015976565038;922.8892532896061;923.2798417234534;945.4091193004373;963.802420503795;971.9708408747714;981.1464026297132;984.6209209454375;942.8114377124602;958.0706714486937;962.6919385796548;976.9023097690246;980.5587485631423;671.462829736214;672.7174586673609;683.3132701634826;694.1947341084318;702.9507934444591;14205.431782728678;14228.14631122136;14507.202103884281;14520;14538.273086345745;12857.614271571867;12863.871361286361;12988.740225195537;13106.14810037354;13230.603081907559;9883.555240014217;10524.210684198715;10680.25238233324;11090.001899828974;11154.944225278903;3627.873024444158;4111.588841115894;4112.177564487115;4211.599898009684;4276.2516559601945;76636.61681691556;76861.61569192118;77619.61190194012;78954.21045789526;79071.60464197642;9612;9788.706338809847;9942.204623630092;9993.550290236812;10069.54687039085;22085.558288834134;22371.32886013422;22371.44071398228;22812.97341619631;22821.543569128855;7371.447141464304;7447;7498;7499.587522686195;7750;4019.2162528307285;4030.6976976726864;4091.8772436826944;4133.111381053088;4215.473065866767;3549.5208146899836;3622;3624.836882340301;3627.8004709741217;3637.8544858205905;637.8628594852129;648.6335220600346;652.9804105876831;656.0782101147895;657.57914934442;538.1846502548642;570.9486146246858;580.3181262017113;596.6479776931632;602.7799853053691;7902.24928631779;8266.504009759434;8378.8324233515;8389.286910612665;8429.114942930904;510.3442076931126;519.2341296587504;544.8583368324264;575.7437940116608;605.5730709849546;2809.9859500702364;3580.0154957386408;3653.1597732521136;3701.444783282529;3841.5582208045876;87904.5604771972;88800;88876;88941;90997.54501227583;73135.63432182805;73748;75875;76996.61501692567;77420.2257977413;43415.565844341785;62599.6870015647;68820.3117968824;69521.65239173872;70569.58860822652;7709.306162445407;7764.107127680389;7775.222477752406;7792.376609871083;7880.369570434466;48460;49218;49275.50724492709;49546.50453495563;50024.74987625039;62355.6882215586;64945;66870.99693504504;67237.32762672311;68266.61066862637;9204.815903681754;9593.760155996157;10245.487725613839;10399.53202105892;10481.318714283852;10569.947150264199;10769.688553858237;10788.190885683449;10927.180461465581;11072.612458563912;10142.137918277029;10251.58993640246;10333.896661033594;10552.366857988545;10553.366797992145;10105.343152695052;10245;10365.9481702591;10418.739531511774;10538.156947444033;9805.656802011896;10018.549165287724;10058.094771470604;10189.032041956003;10269.691909242734;7991.24083212094;8199.139090395662;8201.30288925448;8375.162483751576;8523.95738021306;7298.744543940937;8665.350098742494;8944.616528311239;9017.05320941318;9023.503707296162 13:20:21 INFO - PROCESS | 1666 | __end_tp_report 13:20:21 INFO - PROCESS | 1666 | __start_cc_report 13:20:21 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,413 13:20:21 INFO - PROCESS | 1666 | __end_cc_report 13:20:21 INFO - PROCESS | 1666 | __startTimestamp1463689221156__endTimestamp 13:20:21 INFO - PROCESS | 1666 | 13:20:21 INFO - PROCESS | 1666 | ------- Summary: start ------- 13:20:21 INFO - PROCESS | 1666 | Number of tests: 6 13:20:21 INFO - PROCESS | 1666 | 13:20:21 INFO - PROCESS | 1666 | [#0] dojo.html Cycles:165 Average:5721.90 Median:5946.58 stddev:679.79 (11.4%) stddev-sans-first:657.01 13:20:21 INFO - PROCESS | 1666 | Values: 3399.6 3425.5 3460.0 3501.8 3518.7 5676.8 5716.9 5733.5 5744.4 5749.1 4724.4 5753.3 5775.2 5795.1 5820.3 4810.4 5161.8 5194.5 5228.2 5238.7 4253.4 4982.6 5046.5 5133.8 5349.6 5226.2 5235.1 5289.0 5299.2 5320.2 5237.0 5241.7 5264.5 5270.1 5298.8 5080.4 5131.5 5176.5 5225.6 5251.8 5580.0 5582.7 5629.2 5666.7 5673.5 6159.0 6162.3 6215.3 6216.3 6260.3 6149.8 6230.8 6296.7 6307.7 6339.4 4820.0 5843.4 5845.2 5848.3 5877.8 6314.6 6319.2 6328.3 6330.0 6332.9 6042.0 6135.2 6156.6 6157.4 6195.6 6296.8 6306.6 6312.5 6321.0 6339.6 4789.7 5165.6 5203.6 6090.7 6298.9 6141.9 6214.2 6265.4 6286.2 6347.7 4882.4 4948.6 4961.7 4969.9 4987.0 4474.0 4507.2 4507.7 4522.7 4532.5 4693.8 4747.5 5693.5 5795.5 5808.6 6279.7 6296.1 6297.8 6297.9 6322.1 5803.3 5828.3 5843.7 5851.2 5881.6 5339.8 5408.9 5455.3 5490.9 5501.2 6371.7 6425.8 6439.5 6457.5 6459.9 6191.1 6321.2 6321.3 6322.7 6325.9 6336.9 6385.5 6390.2 6406.4 6431.9 6191.4 6244.9 6245.9 6247.7 6255.3 6182.8 6204.1 6336.8 6338.8 6348.7 5250.2 6084.4 6118.3 6181.1 6209.3 6149.6 6165.7 6177.6 6180.8 6183.2 4617.5 5779.0 5784.0 5946.6 6014.1 6133.8 6140.5 6149.4 6151.8 6176.9 6040.8 6046.4 6052.1 6063.5 6065.0 13:20:21 INFO - PROCESS | 1666 | 13:20:21 INFO - PROCESS | 1666 | [#1] ext.html Cycles:180 Average:72788.95 Median:37330.85 stddev:181033.33 (484.9%) stddev-sans-first:181459.85 13:20:21 INFO - PROCESS | 1666 | Values: 519.2 531.8 539.8 540.8 575.3 26974.1 31843.2 32002.7 33358.8 39512.2 41178.0 41844.0 41893.8 41916.6 41983.6 8226.8 9486.2 9675.1 9728.0 9735.1 8450.5 8686.9 9496.1 9851.1 9881.6 9795.7 9827.5 9874.0 9874.6 9896.2 8338.7 9842.2 9856.5 9860.2 9861.7 9690.3 9723.4 9740.3 9756.4 9795.3 31814.0 40015.4 40315.0 40386.6 40405.0 2778.4 3085.0 3340.5 3436.6 3749.4 26847.3 27016.0 27117.5 27151.5 27194.9 2667.9 2675.5 2720.5 2745.4 2772.9 319992.8 363644.2 393712.0 394098.0 395996.0 35669.6 35958.0 36784.3 37236.3 37425.4 36145.3 36249.8 36483.8 36604.8 36736.0 15761.4 15780.7 15957.8 16129.6 16137.9 13170.3 13197.6 13232.9 13258.7 13453.8 8050.8 8112.6 8267.2 8399.5 8493.9 2841.6 2933.5 3445.1 3458.2 3481.1 958.5 971.1 1025.0 1056.1 1136.7 28968.3 29473.7 29620.4 29737.4 30251.9 953.5 1137.3 1140.0 1145.6 1209.3 15328.6 15618.0 15950.8 16742.2 17079.6 605587.0 982924.0 1006025.0 1015979.0 1020079.0 64128.0 64307.4 65101.7 65163.0 65187.0 1510.5 1516.1 1523.6 1524.7 1531.5 30295.8 31527.4 31602.7 32107.8 32284.4 56499.4 57279.4 58193.1 58264.7 58285.7 486558.0 553696.0 566279.0 590407.0 591227.0 27878.3 29078.3 29316.9 29331.1 29458.7 40823.8 41280.4 45524.8 45950.9 48307.5 39473.4 39829.4 40403.4 40716.2 41096.0 37924.6 38832.8 39216.0 39511.8 39770.8 31572.4 38448.8 39491.6 39559.0 40021.0 33223.3 34588.7 35149.8 35327.8 35411.3 25967.1 26001.2 26117.0 26126.5 26481.6 13:20:21 INFO - PROCESS | 1666 | 13:20:21 INFO - PROCESS | 1666 | [#2] jquery.html Cycles:180 Average:32718.45 Median:21828.67 stddev:94355.43 (432.3%) stddev-sans-first:94588.86 13:20:21 INFO - PROCESS | 1666 | Values: 371.2 394.7 396.7 404.9 407.9 20265.3 20312.6 20846.6 20902.9 21353.3 17775.6 20760.9 21060.5 21070.4 21906.8 4470.5 5792.0 6031.7 6064.5 6071.6 5143.3 5901.5 6000.2 6014.7 6035.9 5698.1 5901.4 5954.6 5984.7 6012.0 5886.6 5931.2 5942.9 5951.0 5957.8 5127.4 5207.5 5212.1 5485.7 5833.9 13121.1 13339.4 13350.5 13359.3 21750.6 2079.2 2166.9 2206.4 2241.0 2264.2 9587.4 9623.2 10087.1 10119.5 12623.6 1673.9 1725.2 1776.7 1806.4 1830.1 120051.4 122361.4 124482.0 128196.0 128453.7 11477.3 11627.3 11923.4 12014.2 12105.5 14829.0 14836.3 14892.4 14922.3 14922.9 8996.2 9021.3 9037.3 9106.8 9122.4 9000.2 9051.4 9159.2 9174.2 9236.4 3922.9 4593.3 4620.7 4685.8 4707.8 1973.1 2013.0 2019.4 2019.6 2023.4 877.8 968.7 985.4 986.1 1013.5 19307.8 20513.6 20546.8 20653.9 20682.8 948.5 964.7 976.2 1008.9 1028.9 10859.5 11000.9 11007.0 11017.9 11039.6 387859.0 591158.0 591773.0 612406.0 614081.0 56425.0 72628.3 89170.6 125036.0 131404.0 1016.4 1017.2 1051.4 1057.3 1070.9 17949.4 18247.5 18291.4 18447.9 19034.3 40049.0 51171.7 51357.5 54134.0 54565.5 69889.0 73011.6 73016.6 73444.0 73931.9 7814.4 7980.0 8029.0 8133.3 8380.2 10941.5 12322.0 12568.9 12667.6 12852.3 9442.8 10877.2 10883.6 11025.8 11193.7 10765.3 10802.0 10849.5 10983.5 11099.4 9334.0 10798.6 11263.9 11420.9 11534.7 8228.5 8271.2 8316.0 8413.4 8578.8 9599.9 11079.1 11096.9 11249.0 11287.4 13:20:21 INFO - PROCESS | 1666 | 13:20:21 INFO - PROCESS | 1666 | [#3] mootools.html Cycles:180 Average:5656.43 Median:5051.36 stddev:3125.87 (61.9%) stddev-sans-first:3119.92 13:20:21 INFO - PROCESS | 1666 | Values: 1618.5 1633.1 1877.8 1926.8 1988.5 7881.5 8396.3 8507.4 8550.9 8554.4 8523.6 8562.2 8570.5 8682.9 8705.3 2299.1 2406.5 2412.5 2413.0 2447.6 2407.6 2418.6 2430.4 2433.2 2459.6 2419.6 2424.4 2445.0 2451.6 2469.3 2409.7 2433.4 2446.7 2447.5 2454.7 2394.7 2405.2 2419.3 2437.7 2440.3 8759.7 8794.6 8795.3 8818.4 8859.8 5344.5 5347.1 5398.0 5415.9 5422.2 3477.2 3526.1 3545.0 3551.5 3596.0 809.8 866.3 1022.1 1039.9 1054.2 11663.0 11791.4 11792.1 11794.5 11810.7 4764.5 4806.2 4838.3 4862.2 4870.4 8019.0 9981.4 10013.1 10145.1 10145.9 5332.5 5376.9 5430.2 5431.6 5469.6 4399.1 4761.8 4774.9 4875.1 4957.1 4023.6 4032.5 4124.2 4127.6 4143.6 1352.8 1364.9 1366.5 1370.6 1372.0 898.5 904.9 925.9 935.3 953.0 5482.2 5771.9 5957.4 5979.9 5994.6 930.3 942.1 996.3 1025.1 1027.3 3727.1 3926.8 3963.5 3986.7 4016.3 10524.5 10794.1 10889.9 10936.8 10946.3 9958.9 10348.0 10672.5 10750.0 10778.2 8311.5 8345.1 8371.2 8433.7 8537.7 5535.3 5579.8 5622.7 5649.4 5674.7 9376.0 9616.8 9686.0 9691.8 9722.5 9426.5 9919.8 9973.8 9980.7 9996.5 5394.0 6370.8 6495.3 7020.1 7119.5 8127.5 8137.6 8196.3 8201.3 8203.3 6915.4 7138.4 7293.8 7318.0 7441.7 6746.7 7165.6 7176.4 7233.4 7283.8 6838.6 6841.8 6906.7 6956.7 6981.0 4825.2 4829.7 4881.6 4981.4 5020.0 4235.8 4959.6 4985.6 5029.1 5073.6 13:20:21 INFO - PROCESS | 1666 | 13:20:21 INFO - PROCESS | 1666 | [#4] prototype.html Cycles:180 Average:5907.19 Median:6825.04 stddev:1968.12 (28.8%) stddev-sans-first:1928.31 13:20:21 INFO - PROCESS | 1666 | Values: 311.6 315.8 325.8 327.8 330.7 6644.8 6744.6 6825.4 6895.1 6979.8 6822.6 6853.6 6872.8 6875.5 6884.2 4950.9 4957.6 4983.5 4985.5 5019.7 4986.4 4989.7 4997.6 5009.2 5020.7 4229.1 4272.8 4932.0 4937.0 4972.6 4630.1 4821.1 4936.7 4942.2 4963.5 4060.9 4061.5 4071.8 4079.8 4082.9 6394.6 6466.4 6510.3 6520.8 6556.7 5741.5 5752.2 5765.1 5778.3 5787.1 7214.6 7224.0 7224.5 7246.0 7252.0 3772.2 3803.9 4425.7 4626.1 4629.3 7501.4 7634.3 7667.6 7695.6 7744.5 3689.4 4699.6 4705.8 4706.8 4707.1 4771.4 4779.5 4783.4 4796.8 4807.9 5735.7 5740.7 5754.8 5756.2 5774.6 7026.6 7284.1 7295.0 7342.4 7361.6 1348.4 1642.9 1663.5 1666.7 1671.0 982.3 1034.0 1041.2 1058.7 1059.5 6674.7 6767.3 6801.2 6869.6 6910.0 7603.6 7605.0 7615.8 7631.5 7665.9 6152.4 6818.9 6824.7 6826.1 6878.0 3420.6 3903.8 3953.4 3998.0 4057.5 8032.5 8054.6 8064.2 8076.1 8079.4 7934.0 7937.1 7977.5 7992.4 8023.8 6951.7 6954.4 7529.3 7609.6 8005.6 7382.6 7660.2 7676.0 7689.0 7702.4 7513.2 7532.2 7541.2 7564.4 7585.0 7261.9 8015.8 8026.7 8040.6 8058.4 4132.9 4221.0 4442.6 4675.6 4687.9 5417.1 7269.9 7431.9 7564.4 7667.1 7333.7 7347.6 7370.7 7377.7 7398.7 6084.8 7214.1 7306.0 7309.6 7345.6 7112.8 7123.1 7130.9 7143.4 7168.9 6011.2 7250.4 7303.3 7340.8 7359.6 6954.5 7093.1 7232.4 7237.6 7257.4 13:20:21 INFO - PROCESS | 1666 | 13:20:21 INFO - PROCESS | 1666 | [#5] yui.html Cycles:180 Average:17613.00 Median:642.43 stddev:24684.33 (3842.3%) stddev-sans-first:24720.70 13:20:21 INFO - PROCESS | 1666 | Values: 647.0 698.6 749.6 756.2 756.5 14869.5 14890.5 14943.0 14987.7 15031.7 13829.8 13860.2 13875.0 13884.4 13904.3 714.2 908.0 914.2 930.0 931.8 906.1 908.2 922.8 922.9 923.3 945.4 963.8 972.0 981.1 984.6 942.8 958.1 962.7 976.9 980.6 671.5 672.7 683.3 694.2 703.0 14205.4 14228.1 14507.2 14520.0 14538.3 12857.6 12863.9 12988.7 13106.1 13230.6 9883.6 10524.2 10680.3 11090.0 11154.9 3627.9 4111.6 4112.2 4211.6 4276.3 76636.6 76861.6 77619.6 78954.2 79071.6 9612.0 9788.7 9942.2 9993.6 10069.5 22085.6 22371.3 22371.4 22813.0 22821.5 7371.4 7447.0 7498.0 7499.6 7750.0 4019.2 4030.7 4091.9 4133.1 4215.5 3549.5 3622.0 3624.8 3627.8 3637.9 637.9 648.6 653.0 656.1 657.6 538.2 570.9 580.3 596.6 602.8 7902.2 8266.5 8378.8 8389.3 8429.1 510.3 519.2 544.9 575.7 605.6 2810.0 3580.0 3653.2 3701.4 3841.6 87904.6 88800.0 88876.0 88941.0 90997.5 73135.6 73748.0 75875.0 76996.6 77420.2 43415.6 62599.7 68820.3 69521.7 70569.6 7709.3 7764.1 7775.2 7792.4 7880.4 48460.0 49218.0 49275.5 49546.5 50024.7 62355.7 64945.0 66871.0 67237.3 68266.6 9204.8 9593.8 10245.5 10399.5 10481.3 10569.9 10769.7 10788.2 10927.2 11072.6 10142.1 10251.6 10333.9 10552.4 10553.4 10105.3 10245.0 10365.9 10418.7 10538.2 9805.7 10018.5 10058.1 10189.0 10269.7 7991.2 8199.1 8201.3 8375.2 8524.0 7298.7 8665.4 8944.6 9017.1 9023.5 13:20:21 INFO - PROCESS | 1666 | -------- Summary: end -------- 13:20:21 INFO - PROCESS | 1666 | 13:20:21 INFO - TEST-INFO | 1666: exit 0 13:20:21 INFO - TEST-OK | dromaeo_css | took 1110722ms 13:20:21 INFO - TEST-START | kraken 13:20:21 INFO - operating with platform_type : mac_ 13:20:21 INFO - Initialising browser for kraken test... 13:20:21 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/tmpsACyyM/profile http://localhost:49275/getInfo.html) 13:20:24 INFO - PROCESS | 1675 | __metrics Screen width/height:1600/1200 13:20:24 INFO - PROCESS | 1675 | colorDepth:24 13:20:24 INFO - PROCESS | 1675 | Browser inner width/height: 1024/689 13:20:24 INFO - PROCESS | 1675 | __metrics 13:20:24 ERROR - PROCESS | 1675 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 13:20:25 INFO - TEST-INFO | 1675: exit 0 13:20:25 INFO - Browser initialized. 13:20:25 INFO - Running cycle 1/1 for kraken test... 13:20:25 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/tmpsACyyM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:20:37 INFO - PROCESS | 1678 | RSS: Main: 221466624 13:20:37 INFO - PROCESS | 1678 | 13:20:39 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 13:20:40 INFO - PROCESS | 1678 | RSS: Main: 233140224 13:20:40 INFO - PROCESS | 1678 | 13:20:43 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 13:20:44 INFO - PROCESS | 1678 | RSS: Main: 242130944 13:20:44 INFO - PROCESS | 1678 | 13:20:47 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 13:20:48 INFO - PROCESS | 1678 | RSS: Main: 244391936 13:20:48 INFO - PROCESS | 1678 | 13:20:50 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 13:20:51 INFO - PROCESS | 1678 | RSS: Main: 245903360 13:20:51 INFO - PROCESS | 1678 | 13:20:53 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:20:53 INFO - PROCESS | 1678 | RSS: Main: 245886976 13:20:53 INFO - PROCESS | 1678 | 13:20:56 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 13:20:57 INFO - PROCESS | 1678 | RSS: Main: 246575104 13:20:57 INFO - PROCESS | 1678 | 13:21:00 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 13:21:00 INFO - PROCESS | 1678 | RSS: Main: 246763520 13:21:00 INFO - PROCESS | 1678 | 13:21:03 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 13:21:04 INFO - PROCESS | 1678 | RSS: Main: 246849536 13:21:04 INFO - PROCESS | 1678 | 13:21:06 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:21:06 INFO - PROCESS | 1678 | RSS: Main: 247242752 13:21:06 INFO - PROCESS | 1678 | 13:21:08 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:21:09 INFO - PROCESS | 1678 | RSS: Main: 247197696 13:21:09 INFO - PROCESS | 1678 | 13:21:11 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:21:12 INFO - PROCESS | 1678 | RSS: Main: 247578624 13:21:12 INFO - PROCESS | 1678 | 13:21:14 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:21:15 INFO - PROCESS | 1678 | RSS: Main: 247595008 13:21:15 INFO - PROCESS | 1678 | 13:21:18 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:21:19 INFO - PROCESS | 1678 | RSS: Main: 247517184 13:21:19 INFO - PROCESS | 1678 | 13:21:21 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 13:21:21 INFO - PROCESS | 1678 | RSS: Main: 248995840 13:21:21 INFO - PROCESS | 1678 | 13:21:21 INFO - PROCESS | 1678 | __start_tp_report 13:21:21 INFO - PROCESS | 1678 | _x_x_mozilla_page_load 13:21:21 INFO - PROCESS | 1678 | _x_x_mozilla_page_load_details 13:21:21 INFO - PROCESS | 1678 | |i|pagename|runs| 13:21:21 INFO - PROCESS | 1678 | |0;ai-astar;115;115;107;116;134;158;109;115;131;118 13:21:21 INFO - PROCESS | 1678 | |1;audio-beat-detection;187;157;145;173;156;150;162;169;171;104 13:21:21 INFO - PROCESS | 1678 | |2;audio-dft;210;231;204;254;185;274;271;198;195;271 13:21:21 INFO - PROCESS | 1678 | |3;audio-fft;64;83;72;137;48;60;72;93;129;74 13:21:21 INFO - PROCESS | 1678 | |4;audio-oscillator;82;83;130;78;80;78;119;82;82;120 13:21:21 INFO - PROCESS | 1678 | |5;imaging-gaussian-blur;89;86;100;130;88;86;88;100;134;85 13:21:21 INFO - PROCESS | 1678 | |6;imaging-darkroom;91;94;94;149;132;96;116;149;95;96 13:21:21 INFO - PROCESS | 1678 | |7;imaging-desaturate;105;110;130;142;143;91;92;90;93;91 13:21:21 INFO - PROCESS | 1678 | |8;json-parse-financial;61;65;65;65;66;64;66;79;62;60 13:21:21 INFO - PROCESS | 1678 | |9;json-stringify-tinderbox;62;69;73;66;73;73;64;69;70;87 13:21:21 INFO - PROCESS | 1678 | |10;stanford-crypto-aes;81;89;101;107;73;96;68;98;74;96 13:21:21 INFO - PROCESS | 1678 | |11;stanford-crypto-ccm;126;130;130;127;130;131;131;132;177;174 13:21:21 INFO - PROCESS | 1678 | |12;stanford-crypto-pbkdf2;196;219;167;170;153;201;213;187;216;189 13:21:21 INFO - PROCESS | 1678 | |13;stanford-crypto-sha256-iterative;60;60;68;80;94;97;101;93;101;95 13:21:21 INFO - PROCESS | 1678 | __end_tp_report 13:21:21 INFO - PROCESS | 1678 | __start_cc_report 13:21:21 INFO - PROCESS | 1678 | _x_x_mozilla_cycle_collect,1095 13:21:21 INFO - PROCESS | 1678 | __end_cc_report 13:21:21 INFO - PROCESS | 1678 | __startTimestamp1463689281303__endTimestamp 13:21:21 INFO - PROCESS | 1678 | 13:21:21 INFO - PROCESS | 1678 | ------- Summary: start ------- 13:21:21 INFO - PROCESS | 1678 | Number of tests: 14 13:21:21 INFO - PROCESS | 1678 | 13:21:21 INFO - PROCESS | 1678 | [#0] ai-astar Cycles:10 Average:121.80 Median:117.00 stddev:15.32 (13.1%) stddev-sans-first:16.06 13:21:21 INFO - PROCESS | 1678 | Values: 115.0 115.0 107.0 116.0 134.0 158.0 109.0 115.0 131.0 118.0 13:21:21 INFO - PROCESS | 1678 | 13:21:21 INFO - PROCESS | 1678 | [#1] audio-beat-detection Cycles:10 Average:157.40 Median:165.50 stddev:22.42 (13.5%) stddev-sans-first:21.06 13:21:21 INFO - PROCESS | 1678 | Values: 187.0 157.0 145.0 173.0 156.0 150.0 162.0 169.0 171.0 104.0 13:21:21 INFO - PROCESS | 1678 | 13:21:21 INFO - PROCESS | 1678 | [#2] audio-dft Cycles:10 Average:229.30 Median:242.50 stddev:35.31 (14.6%) stddev-sans-first:36.75 13:21:21 INFO - PROCESS | 1678 | Values: 210.0 231.0 204.0 254.0 185.0 274.0 271.0 198.0 195.0 271.0 13:21:21 INFO - PROCESS | 1678 | 13:21:21 INFO - PROCESS | 1678 | [#3] audio-fft Cycles:10 Average:83.20 Median:72.00 stddev:29.00 (40.3%) stddev-sans-first:29.92 13:21:21 INFO - PROCESS | 1678 | Values: 64.0 83.0 72.0 137.0 48.0 60.0 72.0 93.0 129.0 74.0 13:21:21 INFO - PROCESS | 1678 | 13:21:21 INFO - PROCESS | 1678 | [#4] audio-oscillator Cycles:10 Average:93.40 Median:81.00 stddev:20.69 (25.5%) stddev-sans-first:21.53 13:21:21 INFO - PROCESS | 1678 | Values: 82.0 83.0 130.0 78.0 80.0 78.0 119.0 82.0 82.0 120.0 13:21:21 INFO - PROCESS | 1678 | 13:21:21 INFO - PROCESS | 1678 | [#5] imaging-gaussian-blur Cycles:10 Average:98.60 Median:86.00 stddev:18.45 (21.4%) stddev-sans-first:19.24 13:21:21 INFO - PROCESS | 1678 | Values: 89.0 86.0 100.0 130.0 88.0 86.0 88.0 100.0 134.0 85.0 13:21:21 INFO - PROCESS | 1678 | 13:21:21 INFO - PROCESS | 1678 | [#6] imaging-darkroom Cycles:10 Average:111.20 Median:94.00 stddev:23.66 (25.2%) stddev-sans-first:23.94 13:21:21 INFO - PROCESS | 1678 | Values: 91.0 94.0 94.0 149.0 132.0 96.0 116.0 149.0 95.0 96.0 13:21:21 INFO - PROCESS | 1678 | 13:21:21 INFO - PROCESS | 1678 | [#7] imaging-desaturate Cycles:10 Average:108.70 Median:90.50 stddev:21.75 (24.0%) stddev-sans-first:23.02 13:21:21 INFO - PROCESS | 1678 | Values: 105.0 110.0 130.0 142.0 143.0 91.0 92.0 90.0 93.0 91.0 13:21:21 INFO - PROCESS | 1678 | 13:21:21 INFO - PROCESS | 1678 | [#8] json-parse-financial Cycles:10 Average:65.30 Median:65.00 stddev:5.25 (8.1%) stddev-sans-first:5.33 13:21:21 INFO - PROCESS | 1678 | Values: 61.0 65.0 65.0 65.0 66.0 64.0 66.0 79.0 62.0 60.0 13:21:21 INFO - PROCESS | 1678 | 13:21:21 INFO - PROCESS | 1678 | [#9] json-stringify-tinderbox Cycles:10 Average:70.60 Median:71.50 stddev:6.92 (9.7%) stddev-sans-first:6.60 13:21:21 INFO - PROCESS | 1678 | Values: 62.0 69.0 73.0 66.0 73.0 73.0 64.0 69.0 70.0 87.0 13:21:21 INFO - PROCESS | 1678 | 13:21:21 INFO - PROCESS | 1678 | [#10] stanford-crypto-aes Cycles:10 Average:88.30 Median:85.00 stddev:13.45 (15.8%) stddev-sans-first:14.00 13:21:21 INFO - PROCESS | 1678 | Values: 81.0 89.0 101.0 107.0 73.0 96.0 68.0 98.0 74.0 96.0 13:21:21 INFO - PROCESS | 1678 | 13:21:21 INFO - PROCESS | 1678 | [#11] stanford-crypto-ccm Cycles:10 Average:138.80 Median:131.00 stddev:19.44 (14.8%) stddev-sans-first:20.06 13:21:21 INFO - PROCESS | 1678 | Values: 126.0 130.0 130.0 127.0 130.0 131.0 131.0 132.0 177.0 174.0 13:21:21 INFO - PROCESS | 1678 | 13:21:21 INFO - PROCESS | 1678 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.10 Median:198.50 stddev:22.36 (11.3%) stddev-sans-first:23.64 13:21:21 INFO - PROCESS | 1678 | Values: 196.0 219.0 167.0 170.0 153.0 201.0 213.0 187.0 216.0 189.0 13:21:21 INFO - PROCESS | 1678 | 13:21:21 INFO - PROCESS | 1678 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:84.90 Median:86.50 stddev:16.55 (19.1%) stddev-sans-first:14.90 13:21:21 INFO - PROCESS | 1678 | Values: 60.0 60.0 68.0 80.0 94.0 97.0 101.0 93.0 101.0 95.0 13:21:21 INFO - PROCESS | 1678 | -------- Summary: end -------- 13:21:21 INFO - PROCESS | 1678 | 13:21:22 INFO - TEST-INFO | 1678: exit 0 13:21:22 INFO - TEST-OK | kraken | took 60371ms 13:21:22 INFO - SUITE-END | took 1171s 13:21:22 INFO - Completed test suite (00:19:31) 13:21:22 INFO - javascript benchmark 13:21:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3399.5580574525306, 3425.5033020212086, 3460.0, 3501.842417091231, 3518.7156687808942, 5676.829695109148, 5716.914246286309, 5733.48398644122, 5744.368119506851, 5749.051406517925, 4724.433068031833, 5753.338366087901, 5775.2203452533895, 5795.130730390443, 5820.272465941757, 4810.374651295327, 5161.81933632322, 5194.480551944813, 5228.215767634866, 5238.68567885928, 4253.447051883266, 4982.626303027288, 5046.54581087702, 5133.845984620468, 5349.598780091489, 5226.216067589873, 5235.057689615867, 5289.0, 5299.231611416342, 5320.17537281722, 5236.973815130938, 5241.685498870061, 5264.52619264268, 5270.104082305978, 5298.802407102122, 5080.364954380702, 5131.461196574343, 5176.456472070416, 5225.555827754645, 5251.842444726664, 5579.995600791855, 5582.720863956786, 5629.155626656035, 5666.688332141731, 5673.489385955242, 6158.9692051539905, 6162.26052873658, 6215.316315205324, 6216.347283535254, 6260.280067792217, 6149.815505534841, 6230.781922632731, 6296.6536840473755, 6307.684615769193, 6339.36606339367, 4820.0, 5843.3864444233595, 5845.1816745655215, 5848.3274423441, 5877.823665290048, 6314.62112273265, 6319.24169099711, 6328.303886572473, 6330.0, 6332.936670633327, 6041.969790151109, 6135.202423684982, 6156.630602163885, 6157.415045570664, 6195.628262304276, 6296.8425789354715, 6306.59007164542, 6312.5265605078885, 6321.0, 6339.619622822645, 4789.712617242976, 5165.560927321145, 5203.60972927032, 6090.69050154219, 6298.937010629927, 6141.946315431794, 6214.1921550198185, 6265.373462653789, 6286.151369565142, 6347.714352854133, 4882.365292512021, 4948.554630083309, 4961.677490963076, 4969.875753106129, 4987.002599480118, 4473.977630111829, 4507.166174257773, 4507.661925355612, 4522.683412161117, 4532.456105267323, 4693.7653117344, 4747.454042785055, 5693.544516438675, 5795.507381872504, 5808.564357673107, 6279.686015699288, 6296.118543403835, 6297.779577714758, 6297.874042519124, 6322.114903913456, 5803.303603567514, 5828.329742079715, 5843.678597677169, 5851.239338885917, 5881.588288819741, 5339.81310654133, 5408.891822163535, 5455.34535855692, 5490.890182196334, 5501.202325662756, 6371.681415929278, 6425.839354016187, 6439.484841212692, 6457.515686323544, 6459.870802583922, 6191.071339299141, 6321.1782468278825, 6321.304656487875, 6322.747090116344, 6325.873482530232, 6336.9049464259, 6385.45723613498, 6390.201224846895, 6406.423421892052, 6431.871362572629, 6191.4118158774845, 6244.906326405198, 6245.9063114052415, 6247.68761561911, 6255.343188965093, 6182.752689892354, 6204.069389591597, 6336.809895703136, 6338.841528961812, 6348.682565871595, 5250.18622113573, 6084.421979911901, 6118.345080237391, 6181.134641150333, 6209.348018458178, 6149.600275982047, 6165.691715414122, 6177.598456100339, 6180.845478862884, 6183.196184495987, 4617.492075871719, 5778.959787238337, 5784.0, 5946.583739138218, 6014.097885317237, 6133.793750436688, 6140.508759299156, 6149.446549810538, 6151.784687535915, 6176.907346389898, 6040.788572399945, 6046.365131661112, 6052.0921861719335, 6063.5452341073715, 6065.0], "value": 5680.446213694245, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [519.2159838643648, 531.7979167916192, 539.8380485854243, 540.7701726766121, 575.272280565085, 26974.055908043247, 31843.203919902015, 32002.679973200262, 33358.833205833966, 39512.20975580487, 41178.0, 41844.0, 41893.79053104738, 41916.58083419165, 41983.58016419835, 8226.835463290761, 9486.193673537757, 9675.080867317594, 9727.951360243189, 9735.075167859077, 8450.450720703166, 8686.914135733034, 9496.050394960517, 9851.064148905842, 9881.555330010167, 9795.657151999681, 9827.459489728062, 9873.950630246838, 9874.555644995957, 9896.15882649972, 8338.749837504854, 9842.212622990144, 9856.50717464124, 9860.161886239679, 9861.654842080492, 9690.32167748258, 9723.416595004323, 9740.318177727562, 9756.414615123116, 9795.314327997043, 31814.0, 40015.39976900349, 40315.0, 40386.59613403858, 40405.0, 2778.4443111377805, 3085.0, 3340.4822252550894, 3436.553248077759, 3749.362608356586, 26847.32881677954, 27016.0, 27117.45765084707, 27151.45697086067, 27194.864025679744, 2667.8799454024615, 2675.531781938153, 2720.5239083160563, 2745.437185377013, 2772.88908443662, 319992.8000719986, 363644.18177909206, 393712.0, 394097.99999999715, 395996.0, 35669.64330356715, 35958.0, 36784.26431471355, 37236.25527489435, 37425.43861842075, 36145.27709445796, 36249.81875090607, 36483.81758091193, 36604.816975915484, 36736.0, 15761.448349307719, 15780.68438631221, 15957.840421595867, 16129.596760081093, 16137.919310403373, 13170.275634840176, 13197.604071877859, 13232.933835330761, 13258.668533286746, 13453.798193027113, 8050.798730031679, 8112.553809540532, 8267.214614611603, 8399.454035487674, 8493.915060849313, 2841.6305880235436, 2933.5013047781927, 3445.0526105320737, 3458.187325978393, 3481.094915321984, 958.4632605740808, 971.121135372489, 1024.9641262555774, 1056.0917610854658, 1136.7215032317135, 28968.275793105342, 29473.705262947096, 29620.40759184804, 29737.40525189484, 30251.88236823401, 953.5327689432268, 1137.2778285788584, 1139.9968028135188, 1145.5933143733855, 1209.2683926224684, 15328.616784580474, 15617.98483098595, 15950.76073858893, 16742.162891855358, 17079.573010674583, 605587.0, 982924.0, 1006025.0, 1015979.0, 1020079.0, 64128.0, 64307.35692643107, 65101.67449162724, 65163.0, 65187.044388668415, 1510.4788847847476, 1516.105497756329, 1523.6190952261713, 1524.7484165112633, 1531.4563330017907, 30295.848520757256, 31527.369452611278, 31602.683973159976, 32107.83946080255, 32284.35431291408, 56499.43500565106, 57279.42720572741, 58193.12710309433, 58264.70867645634, 58285.708571456875, 486558.0, 553696.0, 566279.0116296518, 590407.0, 591227.0, 27878.3030424241, 29078.27304317409, 29316.853415732785, 29331.12006639804, 29458.705412945597, 40823.79588102041, 41280.38079428871, 45524.77237613791, 45950.85122871955, 48307.51692483171, 39473.40789888096, 39829.40255896106, 40403.393949091376, 40716.18567628571, 41096.0, 37924.62075379321, 38832.80583597064, 39216.0, 39511.80244098761, 39770.80114599408, 31572.36855262836, 38448.80775596104, 39491.605083949944, 39559.0, 40021.0, 33223.335533288715, 34588.65411345854, 35149.824250878584, 35327.82336088406, 35411.291774164885, 25967.091151809596, 26001.219963401127, 26116.955321786914, 26126.477470450103, 26481.60277595876], "value": 18645.473335498977, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [371.1667306896018, 394.6665068017526, 396.66283658889927, 404.854252469111, 407.9163771426858, 20265.290714824983, 20312.593748125048, 20846.583068338623, 20902.89548552257, 21353.2631789437, 17775.644487110283, 20760.896195519003, 21060.473488162803, 21070.367888963316, 21906.78093219067, 4470.53059428761, 5791.971040144815, 6031.668258245817, 6064.514838812906, 6071.605345652541, 5143.254228136918, 5901.4688678018965, 6000.18997435345, 6014.699265036731, 6035.879282414349, 5698.145278208261, 5901.3803550627, 5954.553408494358, 5984.670843103628, 6012.0, 5886.6468011919205, 5931.169636250928, 5942.8811423771285, 5950.97024514879, 5957.821265362024, 5127.359080114986, 5207.531322181022, 5212.113940630102, 5485.670859748388, 5833.883322333572, 13121.147125436815, 13339.399727012307, 13350.465981360734, 13359.265240411774, 21750.564988700295, 2079.2099002379, 2166.869987800737, 2206.4042708468623, 2241.002753774571, 2264.1509433962265, 9587.376820506644, 9623.1820295275, 10087.092161705408, 10119.544620492095, 12623.558175463815, 1673.8911970721865, 1725.1546742096407, 1776.6979613465612, 1806.3980304625763, 1830.103249407794, 120051.39974300073, 122361.38819305846, 124482.0, 128196.0, 128453.71546284511, 11477.311361318347, 11627.302361858146, 11923.403829808474, 12014.219075760047, 12105.515779368727, 14828.961972662026, 14836.258187090818, 14892.404303827941, 14922.253887305591, 14922.880783941248, 8996.190342869042, 9021.32340074484, 9037.322200834964, 9106.772330691652, 9122.36143469964, 9000.18998290144, 9051.366404351764, 9159.175674189355, 9174.220191283815, 9236.445813251226, 3922.921541569153, 4593.333966574828, 4620.722756634613, 4685.821093191682, 4707.835225767081, 1973.082516629774, 2012.9899350503154, 2019.3538067818452, 2019.6263691217173, 2023.3828682252054, 877.7849426890452, 968.7239136846106, 985.393982700634, 986.0730912941812, 1013.4729940430934, 19307.8069219306, 20513.589728205352, 20546.794532054788, 20653.89673051625, 20682.793172068086, 948.5162567090833, 964.7346979580672, 976.1507488485064, 1008.8839783424853, 1028.93311934724, 10859.457027148612, 11000.889991099986, 11007.0, 11017.944910275557, 11039.613613523488, 387859.0, 591158.0, 591773.0, 612406.0, 614081.0, 56425.0, 72628.27371726216, 89170.55414722885, 125036.0, 131404.0, 1016.3596933931724, 1017.1710056304237, 1051.4112097225425, 1057.3444464432102, 1070.9410982395887, 17949.371771987917, 18247.45257642273, 18291.359802406852, 18447.907760461112, 19034.333798317548, 40049.0, 51171.74414127906, 51357.48642513676, 54134.0, 54565.45434545763, 69889.0, 73011.63494182494, 73016.63491682507, 73444.0, 73931.89102163579, 7814.374850012099, 7980.0, 8029.0, 8133.308668763221, 8380.16198380157, 10941.507632156412, 12322.01423886105, 12568.93715531453, 12667.556635517712, 12852.293123878277, 9442.81114377695, 10877.18421118435, 10883.619073332395, 11025.83461248098, 11193.72015699614, 10765.30025548337, 10802.0, 10849.511771970438, 10983.450827458435, 11099.44502774842, 9334.0, 10798.56805727762, 11263.887361126284, 11420.94289528547, 11534.711632209262, 8228.50628962264, 8271.172882711922, 8316.0, 8413.411061225655, 8578.78553036179, 9599.904000960181, 11079.113670906469, 11096.889031109586, 11248.987591116838, 11287.43562821872], "value": 9364.19095352922, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1618.5387164658073, 1633.1017940132924, 1877.7840548336937, 1926.8169523895233, 1988.492934301753, 7881.5271083735015, 8396.328293736502, 8507.447015943959, 8550.871736923944, 8554.401191916568, 8523.616437260323, 8562.229399354057, 8570.485770853753, 8682.86975695365, 8705.347098967572, 2299.091858715806, 2406.4946361264156, 2412.5416170927556, 2413.0, 2447.571674956884, 2407.626817843237, 2418.613021916494, 2430.3559556717482, 2433.1605596069326, 2459.6187590923346, 2419.6249581314923, 2424.442378253003, 2444.963325550118, 2451.5832308507597, 2469.259222233332, 2409.72288186859, 2433.3551608823677, 2446.669699590559, 2447.486027934136, 2454.6563481112657, 2394.6767186429784, 2405.2423486601665, 2419.250032489916, 2437.683101196833, 2440.341107900857, 8759.737207883774, 8794.648214071429, 8795.252403545706, 8818.426802257833, 8859.822803543893, 5344.465553444663, 5347.1177255752755, 5398.028354896117, 5415.918761218585, 5422.213779002062, 3477.1828620274214, 3526.1360966563097, 3545.0251180925193, 3551.5027896094566, 3595.964040359589, 809.7975506123469, 866.3026263857593, 1022.0801278849021, 1039.859618951447, 1054.2462139570243, 11663.008644265248, 11791.351475668835, 11792.115591330685, 11794.528218871235, 11810.704732381673, 4764.499727528629, 4806.182948898696, 4838.250071238964, 4862.17343051682, 4870.4155501340065, 8019.0, 9981.351212171186, 10013.09882110599, 10145.137663298554, 10145.9492702536, 5332.493413125747, 5376.892462150813, 5430.212619170277, 5431.619786614899, 5469.644473109235, 4399.098184872094, 4761.785719642625, 4774.904501909942, 4875.07373599015, 4957.082939656103, 4023.5574086850424, 4032.495938007749, 4124.1957818225155, 4127.607877251657, 4143.585641435832, 1352.790317500789, 1364.9181049137082, 1366.5217173989224, 1370.6025252676811, 1372.0121512510975, 898.4761724716153, 904.900460949295, 925.8703781470599, 935.3078721745995, 952.9666461673808, 5482.23248745176, 5771.913421298684, 5957.4042595740075, 5979.940200597938, 5994.6403215807195, 930.3208657679932, 942.1332374215738, 996.3274789517047, 1025.123519390925, 1027.2603725317758, 3727.142757165867, 3926.8036598170547, 3963.504561929759, 3986.740861843971, 4016.2770701273466, 10524.473776311153, 10794.136469082612, 10889.945550272356, 10936.835947460795, 10946.343219406861, 9958.850617240747, 10348.0, 10672.46637668129, 10749.978752018545, 10778.245522813484, 8311.542865142475, 8345.082040975487, 8371.16288371158, 8433.662653493791, 8537.658493660185, 5535.308086489189, 5579.832605021856, 5622.678670512344, 5649.37856835756, 5674.650718366659, 9375.953120234355, 9616.807663846825, 9686.0, 9691.757706057406, 9722.465264410524, 9426.481543515172, 9919.801603968024, 9973.800523989625, 9980.650677226262, 9996.500174991366, 5393.9730301348245, 6370.8088757337355, 6495.2855185928565, 7020.122484689414, 7119.501634885508, 8127.512349259064, 8137.633806478604, 8196.303314218358, 8201.26188643025, 8203.30271926869, 6915.377616014583, 7138.428925686037, 7293.781186564412, 7318.048653674989, 7441.665125069277, 6746.696398661974, 7165.641717913979, 7176.354128128493, 7233.421326293778, 7283.798173301558, 6838.555493892881, 6841.794746157623, 6906.723731050702, 6956.721731130698, 6981.022656827947, 4825.155597770306, 4829.661923665451, 4881.633877459134, 4981.402231732215, 5020.0, 4235.830566777295, 4959.62802789785, 4985.551300382838, 5029.145045342374, 5073.568746656576], "value": 4588.225277093045, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [311.5762562914436, 315.76002238298895, 325.76056598400186, 327.7656475619932, 330.65776920886873, 6644.767433139841, 6744.561603495782, 6825.351591598796, 6895.138107736533, 6979.755708550202, 6822.624755638451, 6853.623050732208, 6872.79381618551, 6875.518713690044, 6884.242733299333, 4950.851474455772, 4957.5786058185095, 4983.476734942833, 4985.501449855022, 5019.723915184663, 4986.4265609455015, 4989.65072444929, 4997.600191984632, 5009.2486127080865, 5020.723860187688, 4229.069604686964, 4272.807723652428, 4931.988942266845, 4936.950630493685, 4972.627052971024, 4630.073985202973, 4821.132196204681, 4936.679115857476, 4942.209246520575, 4963.503649635044, 4060.918781624351, 4061.5329237137516, 4071.7760523171214, 4079.77561234132, 4082.8775136745944, 6394.552381333308, 6466.418022378009, 6510.348965103499, 6520.771772987922, 6556.737730490822, 5741.540676745851, 5752.2234498342605, 5765.106408506688, 5778.27771528559, 5787.13193021046, 7214.603196824172, 7223.963880180618, 7224.458165637546, 7246.0, 7251.963740181265, 3772.1889793694486, 3803.8858834235016, 4425.734455932655, 4626.097910907374, 4629.328747331618, 7501.437392195553, 7634.312911837961, 7667.581497422994, 7695.576743279117, 7744.457887947902, 3689.4281386385155, 4699.647526435463, 4705.776498110516, 4706.811727530929, 4707.129181101507, 4771.379720636364, 4779.545943135397, 4783.378160839069, 4796.760161991887, 4807.855764327076, 5735.713214339266, 5740.741666625012, 5754.827355179351, 5756.237006236946, 5774.566907481955, 7026.613536255452, 7284.125904891447, 7294.963525182447, 7342.412606991428, 7361.6319184040585, 1348.447136673959, 1642.9238848554216, 1663.5092647668966, 1666.7083260429376, 1670.991645041767, 982.2731178927543, 1034.0, 1041.156663102889, 1058.730023843915, 1059.4861492176287, 6674.733010679615, 6767.2894346093935, 6801.18385793708, 6869.553479023847, 6910.0, 7603.581803000778, 7605.0, 7615.771526854203, 7631.503952243197, 7665.891879733006, 6152.354002829638, 6818.8636227276165, 6824.727010919508, 6826.146731658543, 6877.965610171917, 3420.589529256505, 3903.8243279051944, 3953.4069889516804, 3997.960020399817, 4057.4522439470293, 8032.518048917083, 8054.556999364974, 8064.193580642006, 8076.111627720946, 8079.434439589172, 7934.0, 7937.126916039231, 7977.481463704957, 7992.440529163019, 8023.756317770755, 6951.65241737911, 6954.374106330454, 7529.322360987538, 7609.581473019037, 8005.599720013976, 7382.630868456663, 7660.233976602295, 7675.961620191863, 7688.961555192188, 7702.383809295131, 7513.248675132443, 7532.246775322424, 7541.20817314196, 7564.357029652541, 7585.0, 7261.927380726125, 8015.799605009922, 8026.719064832703, 8040.55776932251, 8058.435909486514, 4132.925439385721, 4220.957790422057, 4442.555744425532, 4675.6259499239295, 4687.859364219079, 5417.079096553674, 7269.890951635624, 7431.925680743339, 7564.432667550066, 7667.086507127256, 7333.7066517340845, 7347.595882226529, 7370.742024029133, 7377.668004939684, 7398.704051838082, 6084.783043391251, 7214.062171917617, 7305.963470182615, 7309.634518274172, 7345.55926644403, 7112.786616401516, 7123.145222573325, 7130.928690713234, 7143.392811610864, 7168.928310717035, 6011.248593925759, 7250.38371738387, 7303.342699157101, 7340.816479588053, 7359.595222262827, 6954.513184077268, 7093.113360829896, 7232.385247253832, 7237.601931893796, 7257.419406447577], "value": 5237.899370042627, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [646.9971544106634, 698.556416675411, 749.6439191384093, 756.1757684124303, 756.4591317208195, 14869.479568215102, 14890.478833240839, 14943.0, 14987.700245995073, 15031.699366012674, 13829.79255311171, 13860.237686927216, 13875.0, 13884.375203115858, 13904.304784760747, 714.1679942866555, 907.9591418386156, 914.1772404835634, 929.9907000930006, 931.8089791592741, 906.1391677905983, 908.150878928201, 922.8015976565038, 922.8892532896061, 923.2798417234534, 945.4091193004373, 963.802420503795, 971.9708408747714, 981.1464026297132, 984.6209209454375, 942.8114377124602, 958.0706714486937, 962.6919385796548, 976.9023097690246, 980.5587485631423, 671.462829736214, 672.7174586673609, 683.3132701634826, 694.1947341084318, 702.9507934444591, 14205.431782728678, 14228.14631122136, 14507.202103884281, 14520.0, 14538.273086345745, 12857.614271571867, 12863.871361286361, 12988.740225195537, 13106.14810037354, 13230.603081907559, 9883.555240014217, 10524.210684198715, 10680.25238233324, 11090.001899828974, 11154.944225278903, 3627.873024444158, 4111.588841115894, 4112.177564487115, 4211.599898009684, 4276.2516559601945, 76636.61681691556, 76861.61569192118, 77619.61190194012, 78954.21045789526, 79071.60464197642, 9612.0, 9788.706338809847, 9942.204623630092, 9993.550290236812, 10069.54687039085, 22085.558288834134, 22371.32886013422, 22371.44071398228, 22812.97341619631, 22821.543569128855, 7371.447141464304, 7447.0, 7498.0, 7499.587522686195, 7750.0, 4019.2162528307285, 4030.6976976726864, 4091.8772436826944, 4133.111381053088, 4215.473065866767, 3549.5208146899836, 3622.0, 3624.836882340301, 3627.8004709741217, 3637.8544858205905, 637.8628594852129, 648.6335220600346, 652.9804105876831, 656.0782101147895, 657.57914934442, 538.1846502548642, 570.9486146246858, 580.3181262017113, 596.6479776931632, 602.7799853053691, 7902.24928631779, 8266.504009759434, 8378.8324233515, 8389.286910612665, 8429.114942930904, 510.3442076931126, 519.2341296587504, 544.8583368324264, 575.7437940116608, 605.5730709849546, 2809.9859500702364, 3580.0154957386408, 3653.1597732521136, 3701.444783282529, 3841.5582208045876, 87904.5604771972, 88800.0, 88876.0, 88941.0, 90997.54501227583, 73135.63432182805, 73748.0, 75875.0, 76996.61501692567, 77420.2257977413, 43415.565844341785, 62599.6870015647, 68820.3117968824, 69521.65239173872, 70569.58860822652, 7709.306162445407, 7764.107127680389, 7775.222477752406, 7792.376609871083, 7880.369570434466, 48460.0, 49218.0, 49275.50724492709, 49546.50453495563, 50024.74987625039, 62355.6882215586, 64945.0, 66870.99693504504, 67237.32762672311, 68266.61066862637, 9204.815903681754, 9593.760155996157, 10245.487725613839, 10399.53202105892, 10481.318714283852, 10569.947150264199, 10769.688553858237, 10788.190885683449, 10927.180461465581, 11072.612458563912, 10142.137918277029, 10251.58993640246, 10333.896661033594, 10552.366857988545, 10553.366797992145, 10105.343152695052, 10245.0, 10365.9481702591, 10418.739531511774, 10538.156947444033, 9805.656802011896, 10018.549165287724, 10058.094771470604, 10189.032041956003, 10269.691909242734, 7991.24083212094, 8199.139090395662, 8201.30288925448, 8375.162483751576, 8523.95738021306, 7298.744543940937, 8665.350098742494, 8944.616528311239, 9017.05320941318, 9023.503707296162], "value": 6732.25458912797, "unit": "score"}], "name": "dromaeo_css", "value": 7371.74904744053, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 107.0, 116.0, 134.0, 158.0, 109.0, 115.0, 131.0, 118.0], "value": 121.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 157.0, 145.0, 173.0, 156.0, 150.0, 162.0, 169.0, 171.0, 104.0], "value": 157.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [210.0, 231.0, 204.0, 254.0, 185.0, 274.0, 271.0, 198.0, 195.0, 271.0], "value": 229.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 83.0, 72.0, 137.0, 48.0, 60.0, 72.0, 93.0, 129.0, 74.0], "value": 83.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 130.0, 78.0, 80.0, 78.0, 119.0, 82.0, 82.0, 120.0], "value": 93.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 100.0, 130.0, 88.0, 86.0, 88.0, 100.0, 134.0, 85.0], "value": 98.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 94.0, 94.0, 149.0, 132.0, 96.0, 116.0, 149.0, 95.0, 96.0], "value": 111.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 110.0, 130.0, 142.0, 143.0, 91.0, 92.0, 90.0, 93.0, 91.0], "value": 108.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 65.0, 65.0, 65.0, 66.0, 64.0, 66.0, 79.0, 62.0, 60.0], "value": 65.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 69.0, 73.0, 66.0, 73.0, 73.0, 64.0, 69.0, 70.0, 87.0], "value": 70.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 89.0, 101.0, 107.0, 73.0, 96.0, 68.0, 98.0, 74.0, 96.0], "value": 88.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 130.0, 130.0, 127.0, 130.0, 131.0, 131.0, 132.0, 177.0, 174.0], "value": 138.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 219.0, 167.0, 170.0, 153.0, 201.0, 213.0, 187.0, 216.0, 189.0], "value": 191.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 68.0, 80.0, 94.0, 97.0, 101.0, 93.0, 101.0, 95.0], "value": 84.9, "unit": "score"}], "name": "kraken", "value": 1642.6, "alertThreshold": 2.0}]} 13:21:22 INFO - Return code: 0 13:21:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 13:21:22 ERROR - # TBPL SUCCESS # 13:21:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:21:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:21:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:21:22 INFO - 2016-05-19 13:21:22.571 screenresolution[1683:11688] starting screenresolution argv=screenresolution get 13:21:22 INFO - 2016-05-19 13:21:22.573 screenresolution[1683:11688] Display 0: 1600x1200x32@60 13:21:22 INFO - 2016-05-19 13:21:22.579 screenresolution[1684:11691] starting screenresolution argv=screenresolution list 13:21:22 INFO - Available Modes on Display 0 13:21:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:21:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:21:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:21:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:21:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:21:22 INFO - Intel Iris: 13:21:22 INFO - Chipset Model: Intel Iris 13:21:22 INFO - Type: GPU 13:21:22 INFO - Bus: Built-In 13:21:22 INFO - VRAM (Dynamic, Max): 1536 MB 13:21:22 INFO - Vendor: Intel (0x8086) 13:21:22 INFO - Device ID: 0x0a2e 13:21:22 INFO - Revision ID: 0x0009 13:21:22 INFO - Displays: 13:21:22 INFO - Display: 13:21:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:21:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:21:22 INFO - Display Serial Number: 1600x1200 60 13:21:22 INFO - Main Display: Yes 13:21:22 INFO - Mirror: Off 13:21:22 INFO - Online: Yes 13:21:22 INFO - Rotation: Supported 13:21:22 INFO - Return code: 0 13:21:22 INFO - Running post-action listener: _resource_record_post_action 13:21:22 INFO - Running post-run listener: _resource_record_post_run 13:21:23 INFO - Total resource usage - Wall time: 1205s; CPU: 53.0%; Read bytes: 32202240; Write bytes: 659960832; Read time: 323; Write time: 2080 13:21:23 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 340587008; Write bytes: 336877568; Read time: 26594; Write time: 672 13:21:23 INFO - run-tests - Wall time: 1174s; CPU: 53.0%; Read bytes: 31280640; Write bytes: 302648320; Read time: 310; Write time: 1348 13:21:23 INFO - Running post-run listener: _upload_blobber_files 13:21:23 INFO - Blob upload gear active. 13:21:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:21:23 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 13:21:23 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'] 13:21:23 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 13:21:24 INFO - (blobuploader) - INFO - Open directory for files ... 13:21:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 13:21:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:21:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:21:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 13:21:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:21:25 INFO - (blobuploader) - INFO - Done attempting. 13:21:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 13:21:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:21:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:21:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 13:21:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:21:26 INFO - (blobuploader) - INFO - Done attempting. 13:21:26 INFO - (blobuploader) - INFO - Iteration through files over. 13:21:26 INFO - Return code: 0 13:21:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:21:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:21:26 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b58466f6a634c0be5708f04127b792620cdbb8f2d6e2e91238cefd5a2de67b0ea517640d96af61169198027631f9d78fb37d7ea0d8f279de4ae8df0875e67f88", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1b34c911c21497d7cc4739d24c1b5a21e8bb8b46cdeb4506ff43274ef1080c376640e4719ce7bbb8ccede0a3da14149ec6283833529d0415b9392e5274378ec5"} 13:21:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:21:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:21:26 INFO - Contents: 13:21:26 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b58466f6a634c0be5708f04127b792620cdbb8f2d6e2e91238cefd5a2de67b0ea517640d96af61169198027631f9d78fb37d7ea0d8f279de4ae8df0875e67f88", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1b34c911c21497d7cc4739d24c1b5a21e8bb8b46cdeb4506ff43274ef1080c376640e4719ce7bbb8ccede0a3da14149ec6283833529d0415b9392e5274378ec5"} 13:21:26 INFO - Running post-run listener: copy_logs_to_upload_dir 13:21:26 INFO - Copying logs to upload dir... 13:21:26 INFO - mkdir: /builds/slave/test/build/upload/logs 13:21:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=2836.957912 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 47 mins, 17 secs) (at 2016-05-19 13:21:27.001425) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-19 13:21:27.004862) ========= 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.nq8uSYTVM0/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.QnazZYSwtU/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b58466f6a634c0be5708f04127b792620cdbb8f2d6e2e91238cefd5a2de67b0ea517640d96af61169198027631f9d78fb37d7ea0d8f279de4ae8df0875e67f88", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1b34c911c21497d7cc4739d24c1b5a21e8bb8b46cdeb4506ff43274ef1080c376640e4719ce7bbb8ccede0a3da14149ec6283833529d0415b9392e5274378ec5"} build_url:https://queue.taskcluster.net/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010728 build_url: 'https://queue.taskcluster.net/v1/task/MN0bXjEnTqiwp0Jp2HH3bw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b58466f6a634c0be5708f04127b792620cdbb8f2d6e2e91238cefd5a2de67b0ea517640d96af61169198027631f9d78fb37d7ea0d8f279de4ae8df0875e67f88", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1b34c911c21497d7cc4739d24c1b5a21e8bb8b46cdeb4506ff43274ef1080c376640e4719ce7bbb8ccede0a3da14149ec6283833529d0415b9392e5274378ec5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-19 13:21:27.037258) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 13:21:27.037645) ========= 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.nq8uSYTVM0/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.QnazZYSwtU/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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.005109 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 13:21:27.089673) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-19 13:21:27.090620) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-19 13:21:27.091558) ========= ========= Total master_lag: 0.17 =========