builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0125 starttime: 1461701419.39 results: success (0) buildid: 20160426062558 builduid: 18458ff6a626441ea2f2e0a4fd3bd5e1 revision: cbeae0c4410bef3864b3d3f4ad939321c61cb15b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-26 13:10:19.387207) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-26 13:10:19.387626) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 6 secs) (at 2016-04-26 13:10:19.387894) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 6 secs) (at 2016-04-26 13:10:25.798850) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-26 13:10:25.802183) ========= 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.h4O8yhjdm5/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.61t1aKnGNM/Listeners TMPDIR=/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-26 13:10:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.6M=0.001s 2016-04-26 13:10:26 (15.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.499181 ========= master_lag: 0.76 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-26 13:10:27.056569) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 13:10:27.056963) ========= 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.h4O8yhjdm5/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.61t1aKnGNM/Listeners TMPDIR=/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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.026234 ========= master_lag: 0.49 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 13:10:27.576925) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-26 13:10:27.580316) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev cbeae0c4410bef3864b3d3f4ad939321c61cb15b --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 cbeae0c4410bef3864b3d3f4ad939321c61cb15b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.h4O8yhjdm5/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.61t1aKnGNM/Listeners TMPDIR=/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-26 13:10:27,900 truncating revision to first 12 chars 2016-04-26 13:10:27,901 Setting DEBUG logging. 2016-04-26 13:10:27,901 attempt 1/10 2016-04-26 13:10:27,901 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/cbeae0c4410b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-26 13:10:29,263 unpacking tar archive at: mozilla-aurora-cbeae0c4410b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.614752 ========= master_lag: 0.38 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-26 13:10:29.570708) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-26 13:10:29.570976) ========= script_repo_revision: cbeae0c4410bef3864b3d3f4ad939321c61cb15b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-26 13:10:29.571346) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2016-04-26 13:10:29.571614) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2016-04-26 13:10:31.127538) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-04-26 13:10:31.127802) ========= /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.h4O8yhjdm5/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.61t1aKnGNM/Listeners TMPDIR=/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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 13:10:31 INFO - MultiFileLogger online at 20160426 13:10:31 in /builds/slave/test 13:10:31 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 13:10:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:10:31 INFO - {'append_to_log': False, 13:10:31 INFO - 'base_work_dir': '/builds/slave/test', 13:10:31 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 13:10:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:10:31 INFO - 'branch': 'Mozilla-Aurora', 13:10:31 INFO - 'buildbot_json_path': 'buildprops.json', 13:10:31 INFO - 'config_files': ('talos/mac_config.py',), 13:10:31 INFO - 'default_actions': ('clobber', 13:10:31 INFO - 'read-buildbot-config', 13:10:31 INFO - 'download-and-extract', 13:10:31 INFO - 'populate-webroot', 13:10:31 INFO - 'create-virtualenv', 13:10:31 INFO - 'install', 13:10:31 INFO - 'run-tests'), 13:10:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:10:31 INFO - 'download_minidump_stackwalk': True, 13:10:31 INFO - 'download_symbols': 'ondemand', 13:10:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:10:31 INFO - 'tooltool.py': '/tools/tooltool.py', 13:10:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:10:31 INFO - '/tools/misc-python/virtualenv.py')}, 13:10:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:10:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:10:31 INFO - 'installer_path': 'installer.exe', 13:10:31 INFO - 'log_level': 'info', 13:10:31 INFO - 'log_name': 'talos', 13:10:31 INFO - 'log_to_console': True, 13:10:31 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:10:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:10:31 INFO - 'opt_config_files': (), 13:10:31 INFO - 'pip_index': False, 13:10:31 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:10:31 INFO - 'cmd': ('bash', 13:10:31 INFO - '-c', 13:10:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:10:31 INFO - 'enabled': True, 13:10:31 INFO - 'halt_on_failure': False, 13:10:31 INFO - 'name': 'check_screen_resolution'},), 13:10:31 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:10:31 INFO - 'cmd': ('bash', 13:10:31 INFO - '-c', 13:10:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:10:31 INFO - 'enabled': True, 13:10:31 INFO - 'halt_on_failure': False, 13:10:31 INFO - 'name': 'check_screen_resolution'},), 13:10:31 INFO - 'run_cmd_checks_enabled': True, 13:10:31 INFO - 'sps_profile': False, 13:10:31 INFO - 'sps_profile_interval': 0, 13:10:31 INFO - 'suite': 'dromaeojs-e10s', 13:10:31 INFO - 'system_bits': '32', 13:10:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:10:31 INFO - 'title': 't-yosemite-r7-0125', 13:10:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:10:31 INFO - 'use_talos_json': True, 13:10:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:10:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:10:31 INFO - 'work_dir': 'build'} 13:10:31 INFO - ##### 13:10:31 INFO - ##### Running clobber step. 13:10:31 INFO - ##### 13:10:31 INFO - Running pre-action listener: _resource_record_pre_action 13:10:31 INFO - Running main action method: clobber 13:10:31 INFO - rmtree: /builds/slave/test/build 13:10:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:10:32 INFO - Running post-action listener: _resource_record_post_action 13:10:32 INFO - ##### 13:10:32 INFO - ##### Running read-buildbot-config step. 13:10:32 INFO - ##### 13:10:32 INFO - Running pre-action listener: _resource_record_pre_action 13:10:32 INFO - Running main action method: read_buildbot_config 13:10:32 INFO - Using buildbot properties: 13:10:32 INFO - { 13:10:32 INFO - "project": "", 13:10:32 INFO - "product": "firefox", 13:10:32 INFO - "script_repo_revision": "production", 13:10:32 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 13:10:32 INFO - "repository": "", 13:10:32 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 13:10:32 INFO - "buildid": "20160426062558", 13:10:32 INFO - "pgo_build": "False", 13:10:32 INFO - "basedir": "/builds/slave/test", 13:10:32 INFO - "buildnumber": 262, 13:10:32 INFO - "slavename": "t-yosemite-r7-0125", 13:10:32 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 13:10:32 INFO - "platform": "yosemite_r7", 13:10:32 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 13:10:32 INFO - "branch": "mozilla-aurora", 13:10:32 INFO - "repo_path": "releases/mozilla-aurora", 13:10:32 INFO - "revision": "cbeae0c4410bef3864b3d3f4ad939321c61cb15b", 13:10:32 INFO - "stage_platform": "macosx64", 13:10:32 INFO - "builduid": "18458ff6a626441ea2f2e0a4fd3bd5e1", 13:10:32 INFO - "slavebuilddir": "test" 13:10:32 INFO - } 13:10:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 13:10:32 INFO - Running post-action listener: _resource_record_post_action 13:10:32 INFO - ##### 13:10:32 INFO - ##### Running download-and-extract step. 13:10:32 INFO - ##### 13:10:32 INFO - Running pre-action listener: _resource_record_pre_action 13:10:32 INFO - Running main action method: download_and_extract 13:10:32 INFO - mkdir: /builds/slave/test/build/tests 13:10:32 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:10:32 INFO - https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 13:10:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 13:10:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 13:10:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/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 13:10:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/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 13:10:44 INFO - Downloaded 1199 bytes. 13:10:44 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 13:10:44 INFO - Using the following test package requirements: 13:10:44 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 13:10:44 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:10:44 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 13:10:44 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:10:44 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 13:10:44 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:10:44 INFO - u'jsshell-mac.zip'], 13:10:44 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:10:44 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 13:10:44 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 13:10:44 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:10:44 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 13:10:44 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:10:44 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 13:10:44 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:10:44 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 13:10:44 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:10:44 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 13:10:44 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 13:10:44 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:10:44 INFO - https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:10:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 13:10:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 13:10:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/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 13:10:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/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 13:10:48 INFO - Downloaded 18110203 bytes. 13:10:48 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'] 13:10:48 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 13:10:51 INFO - Return code: 0 13:10:51 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 13:10: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:10:51 INFO - https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:10:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 13:10:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 13:10:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/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 13:10:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/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 13:10:52 INFO - Downloaded 18110203 bytes. 13:10:52 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'] 13:10:52 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 13:10:55 INFO - Return code: 0 13:10:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:10:55 INFO - https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 13:10:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 13:10:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 13:10:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/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 13:10:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/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 13:10:57 INFO - Downloaded 10840145 bytes. 13:10:57 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'] 13:10:57 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 13:10:57 INFO - Return code: 0 13:10:57 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:10:57 INFO - https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 13:10:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 13:10:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 13:10:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 13:10:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/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:11:00 INFO - Downloaded 114070774 bytes. 13:11:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 13:11:00 INFO - mkdir: /builds/slave/test/properties 13:11:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:11:00 INFO - Writing to file /builds/slave/test/properties/build_url 13:11:00 INFO - Contents: 13:11:00 INFO - build_url:https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 13:11:02 INFO - Running post-action listener: _resource_record_post_action 13:11:02 INFO - Running post-action listener: set_extra_try_arguments 13:11:02 INFO - ##### 13:11:02 INFO - ##### Running populate-webroot step. 13:11:02 INFO - ##### 13:11:02 INFO - Running pre-action listener: _resource_record_pre_action 13:11:02 INFO - Running main action method: populate_webroot 13:11:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:11:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:11:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:11:02 INFO - '--tppagecycles', 13:11:02 INFO - '7'], 13:11:02 INFO - 'tests': ['tsvgm']}}, 13:11:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:11:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:11:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:11:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:11:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:11:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:11:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:11:02 INFO - 'talos_options': ['--disable-e10s'], 13:11:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:11:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:11:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:11:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:11:02 INFO - 'talos_options': ['--disable-e10s'], 13:11:02 INFO - 'tests': ['damp', 'tps']}, 13:11:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:11:02 INFO - 'tests': ['damp', 'tps']}, 13:11:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:11:02 INFO - 'tests': ['dromaeo_dom']}, 13:11:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:11:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:11:02 INFO - 'tests': ['a11yr', 13:11:02 INFO - 'ts_paint', 13:11:02 INFO - 'tpaint', 13:11:02 INFO - 'sessionrestore', 13:11:02 INFO - 'sessionrestore_no_auto_restore', 13:11:02 INFO - 'tabpaint']}, 13:11:02 INFO - 'other-e10s': {'tests': ['a11yr', 13:11:02 INFO - 'ts_paint', 13:11:02 INFO - 'tpaint', 13:11:02 INFO - 'sessionrestore', 13:11:02 INFO - 'sessionrestore_no_auto_restore', 13:11:02 INFO - 'tabpaint']}, 13:11:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:11:02 INFO - 'ts_paint', 13:11:02 INFO - 'tpaint', 13:11:02 INFO - 'sessionrestore', 13:11:02 INFO - 'sessionrestore_no_auto_restore', 13:11:02 INFO - 'tabpaint']}, 13:11:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:11:02 INFO - 'ts_paint', 13:11:02 INFO - 'tpaint', 13:11:02 INFO - 'sessionrestore', 13:11:02 INFO - 'sessionrestore_no_auto_restore', 13:11:02 INFO - 'tabpaint']}, 13:11:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:11:02 INFO - 'tests': ['a11yr', 13:11:02 INFO - 'ts_paint', 13:11:02 INFO - 'tpaint', 13:11:02 INFO - 'sessionrestore', 13:11:02 INFO - 'sessionrestore_no_auto_restore', 13:11:02 INFO - 'tabpaint']}, 13:11:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:11:02 INFO - 'tests': ['a11yr', 13:11:02 INFO - 'ts_paint', 13:11:02 INFO - 'tpaint', 13:11:02 INFO - 'sessionrestore', 13:11:02 INFO - 'sessionrestore_no_auto_restore', 13:11:02 INFO - 'tabpaint']}, 13:11:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:11:02 INFO - 'tests': ['tsvgx', 13:11:02 INFO - 'tsvgr_opacity', 13:11:02 INFO - 'tart', 13:11:02 INFO - 'tscrollx', 13:11:02 INFO - 'cart']}, 13:11:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:11:02 INFO - 'tsvgr_opacity', 13:11:02 INFO - 'tart', 13:11:02 INFO - 'tscrollx', 13:11:02 INFO - 'cart']}, 13:11:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:11:02 INFO - 'talos_options': ['--disable-e10s'], 13:11:02 INFO - 'tests': ['tp5o']}, 13:11:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:11:02 INFO - 'tests': ['tp5o']}, 13:11:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:11:02 INFO - 'talos_options': ['--disable-e10s', 13:11:02 INFO - '--xperf_path', 13:11:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:11:02 INFO - 'tests': ['tp5n']}, 13:11:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:11:02 INFO - 'talos_options': ['--xperf_path', 13:11:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:11:02 INFO - 'tests': ['tp5n']}}, 13:11:02 INFO - 'talos.zip': {'path': '', 13:11:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:11:02 INFO - Running post-action listener: _resource_record_post_action 13:11:02 INFO - ##### 13:11:02 INFO - ##### Running create-virtualenv step. 13:11:02 INFO - ##### 13:11:02 INFO - Running pre-action listener: _resource_record_pre_action 13:11:02 INFO - Running main action method: create_virtualenv 13:11:02 INFO - Creating virtualenv /builds/slave/test/build/venv 13:11:02 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:11:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:11:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:11:03 INFO - Using real prefix '/tools/python27' 13:11:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:11:03 INFO - Installing distribute.............................................................................................................................................................................................done. 13:11:06 INFO - Installing pip.................done. 13:11:06 INFO - Return code: 0 13:11:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:11:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:11:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:11:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:06 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 0x10349fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034af420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddb948cdb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10333ee00>, '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 0x1031a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103339b20>, '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.h4O8yhjdm5/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.61t1aKnGNM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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:11:06 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:11:06 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:11:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.h4O8yhjdm5/Render', 13:11:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:11:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:11:06 INFO - 'HOME': '/Users/cltbld', 13:11:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:11:06 INFO - 'LOGNAME': 'cltbld', 13:11:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:06 INFO - 'MOZ_NO_REMOTE': '1', 13:11:06 INFO - 'NO_EM_RESTART': '1', 13:11:06 INFO - 'PAGER': '/bin/cat', 13:11:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:11:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:06 INFO - 'PWD': '/builds/slave/test', 13:11:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:11:06 INFO - 'SHELL': '/bin/bash', 13:11:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.61t1aKnGNM/Listeners', 13:11:06 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 13:11:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:11:06 INFO - 'USER': 'cltbld', 13:11:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:11:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:11:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:06 INFO - 'XPC_FLAGS': '0x0', 13:11:06 INFO - 'XPC_SERVICE_NAME': '0', 13:11:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:11:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:06 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:11:06 INFO - Cleaning up... 13:11:06 INFO - Return code: 0 13:11:06 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 13:11:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:11:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:11:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:06 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 0x10349fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034af420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddb948cdb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10333ee00>, '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 0x1031a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103339b20>, '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.h4O8yhjdm5/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.61t1aKnGNM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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:11:06 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:11:06 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:11:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.h4O8yhjdm5/Render', 13:11:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:11:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:11:06 INFO - 'HOME': '/Users/cltbld', 13:11:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:11:06 INFO - 'LOGNAME': 'cltbld', 13:11:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:06 INFO - 'MOZ_NO_REMOTE': '1', 13:11:06 INFO - 'NO_EM_RESTART': '1', 13:11:06 INFO - 'PAGER': '/bin/cat', 13:11:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:11:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:06 INFO - 'PWD': '/builds/slave/test', 13:11:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:11:06 INFO - 'SHELL': '/bin/bash', 13:11:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.61t1aKnGNM/Listeners', 13:11:06 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 13:11:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:11:06 INFO - 'USER': 'cltbld', 13:11:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:11:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:11:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:06 INFO - 'XPC_FLAGS': '0x0', 13:11:06 INFO - 'XPC_SERVICE_NAME': '0', 13:11:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:11:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:07 INFO - Downloading/unpacking psutil>=0.7.1 13:11:07 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:11:07 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:11:07 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:11:07 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:11:07 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:11:07 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:11:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:11:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:11:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:11:10 INFO - Installing collected packages: psutil 13:11:10 INFO - Running setup.py install for psutil 13:11:10 INFO - building 'psutil._psutil_osx' extension 13:11:10 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:11:10 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:11:10 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:11:10 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:11:11 INFO - building 'psutil._psutil_posix' extension 13:11:11 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:11:11 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:11:11 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:11:11 INFO - ^ 13:11:11 INFO - 1 warning generated. 13:11:11 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:11:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:11:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:11:11 INFO - Successfully installed psutil 13:11:11 INFO - Cleaning up... 13:11:11 INFO - Return code: 0 13:11:11 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 13:11:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:11:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:11:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:11 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 0x10349fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034af420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddb948cdb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10333ee00>, '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 0x1031a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103339b20>, '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.h4O8yhjdm5/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.61t1aKnGNM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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:11:11 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:11:11 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:11:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.h4O8yhjdm5/Render', 13:11:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:11:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:11:11 INFO - 'HOME': '/Users/cltbld', 13:11:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:11:11 INFO - 'LOGNAME': 'cltbld', 13:11:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:11 INFO - 'MOZ_NO_REMOTE': '1', 13:11:11 INFO - 'NO_EM_RESTART': '1', 13:11:11 INFO - 'PAGER': '/bin/cat', 13:11:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:11:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:11 INFO - 'PWD': '/builds/slave/test', 13:11:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:11:11 INFO - 'SHELL': '/bin/bash', 13:11:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.61t1aKnGNM/Listeners', 13:11:11 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 13:11:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:11:11 INFO - 'USER': 'cltbld', 13:11:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:11:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:11:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:11 INFO - 'XPC_FLAGS': '0x0', 13:11:11 INFO - 'XPC_SERVICE_NAME': '0', 13:11:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:11:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:11 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 13:11:11 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:11:11 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:11:11 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:11:11 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:11:11 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:11:11 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:11:14 INFO - Downloading mozsystemmonitor-0.0.tar.gz 13:11:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:11:14 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:11:14 INFO - Installing collected packages: mozsystemmonitor 13:11:14 INFO - Running setup.py install for mozsystemmonitor 13:11:14 INFO - Successfully installed mozsystemmonitor 13:11:14 INFO - Cleaning up... 13:11:14 INFO - Return code: 0 13:11:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:11: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')]} 13:11:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11: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')]} 13:11:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:14 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 0x10349fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034af420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddb948cdb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10333ee00>, '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 0x1031a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103339b20>, '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.h4O8yhjdm5/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.61t1aKnGNM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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:11:14 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:11:14 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:11:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.h4O8yhjdm5/Render', 13:11:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:11:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:11:14 INFO - 'HOME': '/Users/cltbld', 13:11:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:11:14 INFO - 'LOGNAME': 'cltbld', 13:11:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:14 INFO - 'MOZ_NO_REMOTE': '1', 13:11:14 INFO - 'NO_EM_RESTART': '1', 13:11:14 INFO - 'PAGER': '/bin/cat', 13:11:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:11:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:14 INFO - 'PWD': '/builds/slave/test', 13:11:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:11:14 INFO - 'SHELL': '/bin/bash', 13:11:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.61t1aKnGNM/Listeners', 13:11:14 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 13:11:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:11:14 INFO - 'USER': 'cltbld', 13:11:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:11:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:11:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:14 INFO - 'XPC_FLAGS': '0x0', 13:11:14 INFO - 'XPC_SERVICE_NAME': '0', 13:11:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:11:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:14 INFO - Downloading/unpacking blobuploader==1.2.4 13:11:14 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:11:14 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:11:14 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:11:14 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:11:14 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:11:14 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:11:16 INFO - Downloading blobuploader-1.2.4.tar.gz 13:11:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:11:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:11:17 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:11:17 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:11:17 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:11:17 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:11:17 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:11:17 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:11:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:11:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:11:17 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:11:17 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:11:17 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:11:17 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:11:17 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:11:17 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:11:18 INFO - Downloading docopt-0.6.1.tar.gz 13:11:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:11:18 INFO - Installing collected packages: blobuploader, requests, docopt 13:11:18 INFO - Running setup.py install for blobuploader 13:11:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:11:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:11:18 INFO - Running setup.py install for requests 13:11:18 INFO - Running setup.py install for docopt 13:11:19 INFO - Successfully installed blobuploader requests docopt 13:11:19 INFO - Cleaning up... 13:11:19 INFO - Return code: 0 13:11:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:11: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')]} 13:11:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11: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')]} 13:11:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:19 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 0x10349fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034af420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddb948cdb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10333ee00>, '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 0x1031a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103339b20>, '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.h4O8yhjdm5/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.61t1aKnGNM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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:11:19 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:11:19 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:11:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.h4O8yhjdm5/Render', 13:11:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:11:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:11:19 INFO - 'HOME': '/Users/cltbld', 13:11:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:11:19 INFO - 'LOGNAME': 'cltbld', 13:11:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:19 INFO - 'MOZ_NO_REMOTE': '1', 13:11:19 INFO - 'NO_EM_RESTART': '1', 13:11:19 INFO - 'PAGER': '/bin/cat', 13:11:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:11:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:19 INFO - 'PWD': '/builds/slave/test', 13:11:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:11:19 INFO - 'SHELL': '/bin/bash', 13:11:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.61t1aKnGNM/Listeners', 13:11:19 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 13:11:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:11:19 INFO - 'USER': 'cltbld', 13:11:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:11:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:11:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:19 INFO - 'XPC_FLAGS': '0x0', 13:11:19 INFO - 'XPC_SERVICE_NAME': '0', 13:11:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:11:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:11:19 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-NsVyZO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:11:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:11:19 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-N070Ou-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:11:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:11:19 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-m_bib1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:11:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:11:19 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-a_JORq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:11:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:11:19 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-Xt3mKQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:11:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:11:19 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-g2RybN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:11:20 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-TJz7pi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:11:20 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-0GkiMt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:11:20 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-BhdKmM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:11:20 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-FesKYZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:11:20 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-yVdyDX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:11:20 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-JtZXOW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:11:20 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-VKxdwu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:11:20 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-3vD_Nt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:11:20 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-iolGhN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:11:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:11:20 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-OtVU0p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:11:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:11:21 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-kBkQuI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:11:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:11:21 INFO - Running setup.py install for manifestparser 13:11:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:11:21 INFO - Running setup.py install for mozcrash 13:11:21 INFO - Running setup.py install for mozdebug 13:11:21 INFO - Running setup.py install for mozdevice 13:11:21 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:11:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:11:21 INFO - Running setup.py install for mozfile 13:11:21 INFO - Running setup.py install for mozhttpd 13:11:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:11:22 INFO - Running setup.py install for mozinfo 13:11:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:11:22 INFO - Running setup.py install for mozInstall 13:11:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:11:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:11:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:11:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:11:22 INFO - Running setup.py install for mozleak 13:11:22 INFO - Running setup.py install for mozlog 13:11:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:11:22 INFO - Running setup.py install for moznetwork 13:11:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:11:22 INFO - Running setup.py install for mozprocess 13:11:22 INFO - Running setup.py install for mozprofile 13:11:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:11:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:11:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:11:23 INFO - Running setup.py install for mozrunner 13:11:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:11:23 INFO - Running setup.py install for mozscreenshot 13:11:23 INFO - Running setup.py install for moztest 13:11:23 INFO - Running setup.py install for mozversion 13:11:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:11:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:11:23 INFO - Cleaning up... 13:11:23 INFO - Return code: 0 13:11:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:11:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:11:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:11:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:23 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 0x10349fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034af420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddb948cdb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10333ee00>, '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 0x1031a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103339b20>, '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.h4O8yhjdm5/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.61t1aKnGNM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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:11:23 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:11:23 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:11:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.h4O8yhjdm5/Render', 13:11:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:11:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:11:23 INFO - 'HOME': '/Users/cltbld', 13:11:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:11:23 INFO - 'LOGNAME': 'cltbld', 13:11:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:23 INFO - 'MOZ_NO_REMOTE': '1', 13:11:23 INFO - 'NO_EM_RESTART': '1', 13:11:23 INFO - 'PAGER': '/bin/cat', 13:11:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:11:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:23 INFO - 'PWD': '/builds/slave/test', 13:11:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:11:23 INFO - 'SHELL': '/bin/bash', 13:11:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.61t1aKnGNM/Listeners', 13:11:23 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 13:11:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:11:23 INFO - 'USER': 'cltbld', 13:11:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:11:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:11:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:23 INFO - 'XPC_FLAGS': '0x0', 13:11:23 INFO - 'XPC_SERVICE_NAME': '0', 13:11:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:11:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:11:24 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-5IO5JZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:11:24 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:11:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:11:24 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-wiXdld-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:11:24 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:11:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:11:24 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-5WbBBb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:11:24 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:11:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:11:24 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-Y7rRdL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:11:24 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:11:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:11:24 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-53OnaE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:11:24 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:11:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:11:24 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-wAFJNY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:11:24 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:11:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:11:24 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-wugGa0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:11:24 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:11:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:11:24 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-BkSrKL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:11:24 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:11:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:11:24 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-SwrMBi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:11:25 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:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:11:25 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-viHobr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:11:25 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:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:11:25 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-27gzhv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:11:25 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:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:11:25 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-BVgAK3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:11:25 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:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:11:25 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-RjSod7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:11:25 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:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:11:25 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-68c8x7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:11:25 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:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:11:25 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-PeL0Ll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:11:25 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:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:11:25 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-56f4dz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:11:25 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:11:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:11:25 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-K2F870-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:11:25 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:11:25 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:11:25 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:11:25 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:11:25 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:11:25 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:11:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:11:25 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:11:25 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:11:25 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:11:25 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:11:25 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:11:25 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:11:28 INFO - Downloading blessings-1.6.tar.gz 13:11:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:11:28 INFO - Installing collected packages: blessings 13:11:28 INFO - Running setup.py install for blessings 13:11:28 INFO - Successfully installed blessings 13:11:28 INFO - Cleaning up... 13:11:28 INFO - Return code: 0 13:11:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:11:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:11:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:11:28 INFO - Reading from file tmpfile_stdout 13:11:28 INFO - Current package versions: 13:11:28 INFO - blessings == 1.6 13:11:28 INFO - blobuploader == 1.2.4 13:11:28 INFO - docopt == 0.6.1 13:11:28 INFO - manifestparser == 1.1 13:11:28 INFO - mozInstall == 1.12 13:11:28 INFO - mozcrash == 0.17 13:11:28 INFO - mozdebug == 0.1 13:11:28 INFO - mozdevice == 0.48 13:11:28 INFO - mozfile == 1.2 13:11:28 INFO - mozhttpd == 0.7 13:11:28 INFO - mozinfo == 0.9 13:11:28 INFO - mozleak == 0.1 13:11:28 INFO - mozlog == 3.1 13:11:28 INFO - moznetwork == 0.27 13:11:28 INFO - mozprocess == 0.22 13:11:28 INFO - mozprofile == 0.28 13:11:28 INFO - mozrunner == 6.11 13:11:28 INFO - mozscreenshot == 0.1 13:11:28 INFO - mozsystemmonitor == 0.0 13:11:28 INFO - moztest == 0.7 13:11:28 INFO - mozversion == 1.4 13:11:28 INFO - psutil == 3.1.1 13:11:28 INFO - requests == 1.2.3 13:11:28 INFO - wsgiref == 0.1.2 13:11:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:11:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:11:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:11:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:28 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 0x10349fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034af420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddb948cdb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10333ee00>, '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 0x1031a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103339b20>, '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.h4O8yhjdm5/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.61t1aKnGNM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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:11:28 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:11:28 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:11:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.h4O8yhjdm5/Render', 13:11:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:11:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:11:28 INFO - 'HOME': '/Users/cltbld', 13:11:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:11:28 INFO - 'LOGNAME': 'cltbld', 13:11:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:28 INFO - 'MOZ_NO_REMOTE': '1', 13:11:28 INFO - 'NO_EM_RESTART': '1', 13:11:28 INFO - 'PAGER': '/bin/cat', 13:11:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:11:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:28 INFO - 'PWD': '/builds/slave/test', 13:11:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:11:28 INFO - 'SHELL': '/bin/bash', 13:11:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.61t1aKnGNM/Listeners', 13:11:28 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 13:11:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:11:28 INFO - 'USER': 'cltbld', 13:11:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:11:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:11:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:28 INFO - 'XPC_FLAGS': '0x0', 13:11:28 INFO - 'XPC_SERVICE_NAME': '0', 13:11:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:11:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:29 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:11:29 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:11:29 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:11:29 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:11:29 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:11:29 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:11:29 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:11:29 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:11:29 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:11:29 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:11:29 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:11:29 INFO - Cleaning up... 13:11:29 INFO - Return code: 0 13:11:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:11: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')]} 13:11:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11: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')]} 13:11:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:29 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 0x10349fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034af420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddb948cdb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10333ee00>, '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 0x1031a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103339b20>, '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.h4O8yhjdm5/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.61t1aKnGNM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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:11:29 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:11:29 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:11:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.h4O8yhjdm5/Render', 13:11:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:11:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:11:29 INFO - 'HOME': '/Users/cltbld', 13:11:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:11:29 INFO - 'LOGNAME': 'cltbld', 13:11:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:29 INFO - 'MOZ_NO_REMOTE': '1', 13:11:29 INFO - 'NO_EM_RESTART': '1', 13:11:29 INFO - 'PAGER': '/bin/cat', 13:11:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:11:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:29 INFO - 'PWD': '/builds/slave/test', 13:11:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:11:29 INFO - 'SHELL': '/bin/bash', 13:11:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.61t1aKnGNM/Listeners', 13:11:29 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 13:11:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:11:29 INFO - 'USER': 'cltbld', 13:11:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:11:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:11:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:29 INFO - 'XPC_FLAGS': '0x0', 13:11:29 INFO - 'XPC_SERVICE_NAME': '0', 13:11:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:11:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:29 INFO - Downloading/unpacking jsonschema 13:11:29 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:11:29 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:11:29 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:11:29 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:11:29 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:11:29 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:11:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:11:31 INFO - Downloading/unpacking functools32 (from jsonschema) 13:11:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:32 INFO - Downloading functools32-3.2.3-2.tar.gz 13:11:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:11:32 INFO - warning: no files found matching '*.txt' 13:11:32 INFO - no previously-included directories found matching 'build' 13:11:32 INFO - no previously-included directories found matching 'dist' 13:11:32 INFO - no previously-included directories found matching '.git*' 13:11:32 INFO - Installing collected packages: jsonschema, functools32 13:11:32 INFO - Running setup.py install for functools32 13:11:32 INFO - warning: no files found matching '*.txt' 13:11:32 INFO - no previously-included directories found matching 'build' 13:11:32 INFO - no previously-included directories found matching 'dist' 13:11:32 INFO - no previously-included directories found matching '.git*' 13:11:32 INFO - Successfully installed jsonschema functools32 13:11:32 INFO - Cleaning up... 13:11:32 INFO - Return code: 0 13:11:32 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 13:11:32 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:11:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:11:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:32 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:11:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:11:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:11:32 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 0x10349fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034af420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fddb948cdb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10333ee00>, '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 0x1031a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103339b20>, '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.h4O8yhjdm5/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.61t1aKnGNM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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:11:32 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:11:32 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:11:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.h4O8yhjdm5/Render', 13:11:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:11:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:11:32 INFO - 'HOME': '/Users/cltbld', 13:11:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:11:32 INFO - 'LOGNAME': 'cltbld', 13:11:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:11:32 INFO - 'MOZ_NO_REMOTE': '1', 13:11:32 INFO - 'NO_EM_RESTART': '1', 13:11:32 INFO - 'PAGER': '/bin/cat', 13:11:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:11:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:11:32 INFO - 'PWD': '/builds/slave/test', 13:11:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:11:32 INFO - 'SHELL': '/bin/bash', 13:11:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.61t1aKnGNM/Listeners', 13:11:32 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 13:11:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:11:32 INFO - 'USER': 'cltbld', 13:11:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:11:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:11:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:11:32 INFO - 'XPC_FLAGS': '0x0', 13:11:32 INFO - 'XPC_SERVICE_NAME': '0', 13:11:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:11:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:11:32 INFO - Downloading/unpacking flake8 13:11:32 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:11:32 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:11:32 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:11:32 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:11:32 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:11:32 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:11:35 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 13:11:35 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 13:11:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:11:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:11:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:11:36 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 13:11:36 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 13:11:36 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:11:36 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:11:36 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:11:36 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:11:36 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:11:36 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:11:36 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 13:11:36 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:11:36 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:11:36 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:11:36 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:11:36 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:11:36 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:11:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 13:11:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:11:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:11:36 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:11:36 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:11:36 INFO - no previously-included directories found matching 'docs/_build' 13:11:36 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 13:11:37 INFO - Running setup.py install for pep8 13:11:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:11:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:11:37 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:11:37 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:11:37 INFO - no previously-included directories found matching 'docs/_build' 13:11:37 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 13:11:37 INFO - Successfully installed flake8 mccabe pyflakes pep8 13:11:37 INFO - Cleaning up... 13:11:37 INFO - Return code: 0 13:11:37 INFO - Running post-action listener: _resource_record_post_action 13:11:37 INFO - Running post-action listener: _start_resource_monitoring 13:11:37 INFO - Starting resource monitoring. 13:11:37 INFO - ##### 13:11:37 INFO - ##### Running install step. 13:11:37 INFO - ##### 13:11:37 INFO - Running pre-action listener: _resource_record_pre_action 13:11:37 INFO - Running main action method: install 13:11:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:11:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:11:37 INFO - Reading from file tmpfile_stdout 13:11:37 INFO - Detecting whether we're running mozinstall >=1.0... 13:11:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:11:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:11:37 INFO - Reading from file tmpfile_stdout 13:11:37 INFO - Output received: 13:11:37 INFO - Usage: mozinstall [options] installer 13:11:37 INFO - Options: 13:11:37 INFO - -h, --help show this help message and exit 13:11:37 INFO - -d DEST, --destination=DEST 13:11:37 INFO - Directory to install application into. [default: 13:11:37 INFO - "/builds/slave/test"] 13:11:37 INFO - --app=APP Application being installed. [default: firefox] 13:11:37 INFO - mkdir: /builds/slave/test/build/application 13:11:37 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:11:37 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:12:09 INFO - Reading from file tmpfile_stdout 13:12:09 INFO - Output received: 13:12:09 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 13:12:09 INFO - Running post-action listener: _resource_record_post_action 13:12:09 INFO - ##### 13:12:09 INFO - ##### Running run-tests step. 13:12:09 INFO - ##### 13:12:09 INFO - Running pre-action listener: _resource_record_pre_action 13:12:09 INFO - Running main action method: run_tests 13:12:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:12:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:12:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:12:09 INFO - 2016-04-26 13:12:09.518 screenresolution[1651:8027] starting screenresolution argv=screenresolution get 13:12:09 INFO - 2016-04-26 13:12:09.521 screenresolution[1651:8027] Display 0: 1600x1200x32@60 13:12:09 INFO - 2016-04-26 13:12:09.527 screenresolution[1652:8029] starting screenresolution argv=screenresolution list 13:12:09 INFO - Available Modes on Display 0 13:12:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:12:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:12:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:12:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:12:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:12:09 INFO - Intel Iris: 13:12:09 INFO - Chipset Model: Intel Iris 13:12:09 INFO - Type: GPU 13:12:09 INFO - Bus: Built-In 13:12:09 INFO - VRAM (Dynamic, Max): 1536 MB 13:12:09 INFO - Vendor: Intel (0x8086) 13:12:09 INFO - Device ID: 0x0a2e 13:12:09 INFO - Revision ID: 0x0009 13:12:09 INFO - Displays: 13:12:09 INFO - Display: 13:12:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:12:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:12:09 INFO - Display Serial Number: 1600x1200 60 13:12:09 INFO - Main Display: Yes 13:12:09 INFO - Mirror: Off 13:12:09 INFO - Online: Yes 13:12:09 INFO - Rotation: Supported 13:12:09 INFO - Return code: 0 13:12:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:12:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:12:09 INFO - Python 2.7.3 13:12:09 INFO - Return code: 0 13:12:09 INFO - grabbing minidump binary from tooltool 13:12: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:12:09 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 0x10333ee00>, '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 0x1031a7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103339b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:12:09 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:12:09 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:12:09 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:12:09 INFO - Return code: 0 13:12:09 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:12:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:12:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:12:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:12:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:12:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 13:12:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 13:12:10 INFO - Return code: 0 13:12:10 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/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0125', '--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:12:10 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/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0125 --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:12:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.h4O8yhjdm5/Render', 13:12:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:12:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:12:10 INFO - 'HOME': '/Users/cltbld', 13:12:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:12:10 INFO - 'LOGNAME': 'cltbld', 13:12:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:12:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:12:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:12:10 INFO - 'MOZ_NO_REMOTE': '1', 13:12:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:12:10 INFO - 'NO_EM_RESTART': '1', 13:12:10 INFO - 'PAGER': '/bin/cat', 13:12:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:12:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:12:10 INFO - 'PWD': '/builds/slave/test', 13:12:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:12:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:12:10 INFO - 'SHELL': '/bin/bash', 13:12:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.61t1aKnGNM/Listeners', 13:12:10 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 13:12:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:12:10 INFO - 'USER': 'cltbld', 13:12:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:12:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:12:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:12:10 INFO - 'XPC_FLAGS': '0x0', 13:12:10 INFO - 'XPC_SERVICE_NAME': '0', 13:12:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:12:10 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/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0125', '--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:12:11 INFO - mozversion application_buildid: 20160426062558 13:12:11 INFO - mozversion application_changeset: cbeae0c4410bef3864b3d3f4ad939321c61cb15b 13:12:11 INFO - mozversion application_display_name: FirefoxDeveloperEdition 13:12:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:12:11 INFO - mozversion application_name: Firefox 13:12:11 INFO - mozversion application_remotingname: firefox-dev 13:12:11 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:12:11 INFO - mozversion application_vendor: Mozilla 13:12:11 INFO - mozversion application_version: 48.0a2 13:12:11 INFO - mozversion platform_buildid: 20160426062558 13:12:11 INFO - mozversion platform_changeset: cbeae0c4410bef3864b3d3f4ad939321c61cb15b 13:12:11 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:12:11 INFO - mozversion platform_version: 48.0a2 13:12:11 INFO - using testdate: 1461701531 13:12:11 INFO - actual date: 1461701531 13:12:11 INFO - starting webserver on 'localhost:49283' 13:12:11 INFO - SUITE-START | Running 2 tests 13:12:11 INFO - TEST-START | dromaeo_css 13:12:11 INFO - operating with platform_type : mac_ 13:12:11 INFO - Initialising browser for dromaeo_css test... 13:12:11 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/tmpKrsb84/profile http://localhost:49283/getInfo.html) 13:12:14 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 13:12:14 INFO - PROCESS | 1670 | colorDepth:24 13:12:14 INFO - PROCESS | 1670 | Browser inner width/height: 1024/689 13:12:14 INFO - PROCESS | 1670 | __metrics 13:12:14 ERROR - PROCESS | 1670 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 13:12:14 INFO - TEST-INFO | 1670: exit 0 13:12:14 INFO - Browser initialized. 13:12:14 INFO - Running cycle 1/1 for dromaeo_css test... 13:12:14 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/tmpKrsb84/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:12:26 INFO - PROCESS | 1674 | RSS: Main: 221696000 13:12:26 INFO - PROCESS | 1674 | 13:13:16 INFO - PROCESS | 1674 | 1461701596328 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:15:26 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49283/tests/dromaeo/cssquery-ext.html) 13:15:27 INFO - PROCESS | 1674 | RSS: Main: 231657472 13:15:27 INFO - PROCESS | 1674 | 13:18:30 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-ext.html (next: http://localhost:49283/tests/dromaeo/cssquery-jquery.html) 13:18:30 INFO - PROCESS | 1674 | RSS: Main: 243400704 13:18:30 INFO - PROCESS | 1674 | 13:21:32 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49283/tests/dromaeo/cssquery-mootools.html) 13:21:33 INFO - PROCESS | 1674 | RSS: Main: 245501952 13:21:33 INFO - PROCESS | 1674 | 13:24:34 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49283/tests/dromaeo/cssquery-prototype.html) 13:24:35 INFO - PROCESS | 1674 | RSS: Main: 247218176 13:24:35 INFO - PROCESS | 1674 | 13:27:38 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49283/tests/dromaeo/cssquery-yui.html) 13:27:38 INFO - PROCESS | 1674 | RSS: Main: 250028032 13:27:38 INFO - PROCESS | 1674 | 13:30:41 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-yui.html (next: http://localhost:49283/tests/dromaeo/cssquery-dojo.html) 13:30:41 INFO - PROCESS | 1674 | RSS: Main: 251203584 13:30:41 INFO - PROCESS | 1674 | 13:30:41 INFO - PROCESS | 1674 | __start_tp_report 13:30:41 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 13:30:41 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 13:30:41 INFO - PROCESS | 1674 | |i|pagename|runs| 13:30:41 INFO - PROCESS | 1674 | |0;dojo.html;2671.826331288466;2864.64191976003;3443.8622455101795;3487.80817055062;3493.074335301144;4150.377443383494;4556.567126123013;5543.029969755292;5641.774329026839;5666.546676265899;5710.200571919924;5717.656940583568;5733;5736.770529178828;5739.971300143494;5145.3053837731895;5158.458361872005;5235.057689615867;5279.788808447676;5320.787168513274;4464.196444639963;5120.590352771769;5187.584993200535;5248.186531087668;5317.494837990404;5270.4729527047375;5306.018386598473;5323.387810401815;5329.946700533003;5349.304590403242;5208.791648334062;5256.237845512398;5259.526642602146;5269.789208431658;5295.735213239323;5072.822451214227;5106.106431374532;5142.665726727751;5211.0359583477175;5225.555827754645;5492.258545096401;5520.751566179531;5552.472515111058;5603.2715746953045;5653.236813030229;5088.643794934356;5179.404368497633;6121.846953826145;6187.938120618781;6293.87412251757;6157.322694503601;6161.075838624198;6206.503479721611;6250.562460627758;6279.780207692755;5835.533157347402;5891.911621325684;5915.3788852170765;5918.852028699274;5923.822285331447;5265.9736701317015;5278.366596008426;6253.874922501524;6342.746290148435;6363.172787537591;5069.8732531687;6059.818205453844;6117.888303246575;6228.128062071313;6236.875262494816;6273.811785646438;6284.5600807943;6292.087647291209;6314.873702526015;6328.493720502349;6156.907646385308;6169.722362493699;6241.563090583706;6275.748970041239;6295.464885484785;5497.8075767348755;6050.42520960508;6094.939050609437;6306.211723534559;6309.274433440121;4933.358663373739;4933.506649335038;4946.604271658259;4948.331975183376;4952.356193694796;4367.737935723867;4451.221036318631;4467.352233926127;4469.351943968107;4470.307102399133;5172.612054095958;5569.220309156722;5652.152177173457;5708.229389032511;5940.70296485174;6193.349698281616;6239.438450539473;6246.219222597176;6246.4378205960775;6250.562460627803;5636.323641163005;5861;5877.715574449421;5895.115732640138;5935.109733539917;5412.296401467784;5455;5480.3971563128025;5486.972565137149;5534.52956498694;6390.265119511316;6418.775342862978;6425.74297028123;6434.935650643433;6442.645654489048;5031.874203144951;6196;6265.248170219603;6268.216472940882;6291.1192433058495;6356.173697419306;6409.4872410206135;6409.9359006411205;6410.935890641033;6413.615183089029;6213.099100630474;6259.906101408574;6263.655498947602;6266.905996410149;6282.560220784501;5262.447443018428;6116.296625887901;6164.537659675632;6171.876562468636;6206.038064100072;6014.428629280212;6081.483073938764;6117.204763380732;6134.528431832502;6170.197874276315;5450.209719590558;6171.13604095418;6179.567430279837;6199.349068347939;6225.62646241227;5479.630092476881;5757.65454072757;5961.433663801932;6038.63768173911;6047.213862197887;5849.415058494116;6078.179445774941;6093.634381937098;6120.418560236771;6126.08108783686;6020.939790602213;6047.969760151171;6051.062085376773;6069.969650151721;6097.207363042776 13:30:41 INFO - PROCESS | 1674 | |1;ext.html;512.2955935588566;524.4178961352895;583.6818933681149;614.1555361378105;624.821925751161;39492.2101557969;40648.59351406485;40668.79665601672;40675.59324406755;40685.77942661718;41624.58375416245;41840.58159418405;42352.9411764706;42356.3646545302;42664;9351.01814309498;9589.37669051513;9596.184324332406;9598.904010959906;9623.711288661352;8501;9518.14336709696;9829.852552211723;9846.40921544706;9852.802943941117;9808.705738827812;9829.705108846745;9859.30984831062;9910.355826871266;9920.900790992106;9747.561359738758;9766.316357854917;9817.410955342702;9844.212463002943;9851.753706157331;9603.18372938301;9653.517324133765;9728.027197280286;9732.75668108296;9753.463559504224;41385.79307103475;41643.37534936978;41917.58082419198;41953.58046419527;42043.1591368174;2764.6129541864157;3043.239190202449;3133.4516459619704;3159.210197450637;3296.912019033713;26711.125309480067;26737.465250694877;27513.31216719578;27839;28185.718142818514;2672.398710290189;2698.298442404969;2720.9455810883674;2779.2608263935113;2827.5758636204528;377292;380056;380495;380855;382850;35302;35869.28261434809;36191.27617447636;36376.27247455036;36387.63612363896;35812.64187358145;36189.27621447556;36268.91193264206;36349.636503634625;36529.086772830895;15816.68366632661;16060.116693581731;16084.115373654562;16108.75836862448;16121.516354509384;13447;13485.797713034312;13507.189568625914;13509.459621615222;13591.456341746418;8238.011438627283;8351.498910065415;8392.286655634218;8450.788730281793;8453.830923381498;3410.386130496485;3415.838614870956;3470.410030294856;3474.913127171841;3476.89569312921;960.8270511308032;1081.9621313254;1101.5924108080062;1104.3981030338325;1151.240181480219;22630.66054009191;30559.541606875915;31144.688553114178;31188.220294492818;31729.889453869004;1042.723678225271;1086.3481910853582;1089.1069323154936;1101.363073110285;1127.143371038017;11550.711232219262;12259.754804903852;15771.211439427983;15955.282012309473;16211.594710132105;670486;680603;688316;688502;688927;63501.047484287774;63929.36070639327;64387.67806160939;64529.67735161388;64684;1476.2102275282887;1505.646173149331;1521.8402067782945;1522.5204060720837;1527.633367991674;25883.87058064773;30527.847360763055;30885.691143088283;31008.68991310058;31511;56550.71724641351;57169;57405.71297143488;57594.42405576058;57762.133567997356;480917;481451;482467;482998;483251;28009.019684311766;28209.012684555943;28271.29321766972;28390.290242744097;28617.856910715313;48017;48213.794655133905;48624.27063593978;48685.26972095492;49794;39244;39824.80087599543;39848;40259.39610905897;40350.58772942933;32618;39531.40702889516;39554.802225988686;39911.40132897951;40435.595644043184;32531.512027319135;38412.23175536418;38746.80626596849;38972.220555589294;39227;30751.384972300875;32576.83711581427;33306.00081997544;34376.82811585926;34507;25157.371065723502;25337;25692.614610781227;25820.741792581834;25908.870455648354 13:30:41 INFO - PROCESS | 1674 | |2;jquery.html;392.28016589558143;394.46155997064005;399.9080211551343;401.44800898764197;403.78397557306846;20002.59994800105;21019.528632995676;21129.894350528244;21291.574168516658;22634.773652263513;21325.893370533126;21352;21638.3508494745;22229.666555001688;22281;5469.617126801136;6084.634921904678;6094.908576371358;6115.69421528924;6119.877602447948;4988.700677959316;5913.9059274034225;6025.969870150643;6064.241969753781;6131.632102073868;5597.888042239153;5939.168516407706;5956.55325850563;5967.194428752128;5984.670843103628;5823.951688696033;5886.911696324559;5940.584159108864;5969.641821490724;5992.161097446361;5240.895182096337;5250.501202385767;5264.315638966948;5273;5285.233641122016;12980.026498012592;13730.244836533986;25742;26633.201003969913;26769;2129.9148034078617;2187.0704950396016;2230.14139556272;2347.507023525062;2497.8751062446986;9768;10230.028147325993;10326;13575.981801364936;13745.587632371045;1694.2121913310248;1727.3349760342232;1737.5569229846308;1763.8147994460653;1784.8929064256056;123752;125284;126966.3651681745;129028.35485822604;130440;11951.282923024646;12034.398280085961;12097.092718046006;12113.212641178296;12151.75696486083;11785.3518056506;12725.300108493937;12807.871921280854;15423.305951232222;15533.844661553467;7832.882506762289;7944.205579442009;9099;9124.908750912406;9205.125513076247;8975.865362019575;8992.145746154105;9053.004169541346;9065.728028159034;9129.726108216764;4171.186618609342;4559.658025648089;4723.244280915037;4780.498047704941;4814.398200224972;1946.8247857692875;1991.4822146242082;2021.6158929803291;2023.089609675652;2058.475088852363;745.2956955676833;954.4893481987103;989.8614194012844;1006.3408467453829;1010.5099026971913;20358.18567257293;21019.264325748525;21058.78941210599;21126.577468450545;21483.67774483384;845.8942632170979;945.239082538563;968.4189486308159;1033.1682995188944;1070.4701172919454;8241.423100382915;10984.615538456275;11265.380404077854;11299.604513841976;11307.717307067225;585545;601239;605378;621941;622721;134871.32564337115;138183.30908345393;139970.30014850065;140932;143714.2814285943;1000.9199264058858;1051.5793682526898;1058.2856571813995;1058.915286777056;1060.5068643081004;18313.267469301078;18544.536386590444;18765.34321298748;18918.716219256996;19036.33372831944;50519;56309.15536267041;56342.71828640831;57142;57241.427585723606;79629;83190.16809831825;83404;84050.57974710087;85022.72465912893;7961.920380796118;8160.591970401575;8218.301444377055;8225.095239523527;8230.300424463987;11681.240719353214;12256.325902075472;12486.687832804253;12505.56230531927;12635.810462842881;10784.89215107839;10791.190660700322;10841.241113122322;10867.836982445428;10955.452227388758;10628.415437150721;10705.143588513056;10722.088622467178;11028.448577570929;11038.17213708959;10793.946030270112;11047.668569942593;11253.549858005588;11284.379359135326;11336.036436902627;8068.919310806817;8078.959605201936;8161.694128939341;8188.918110818816;8248.505089694638;8130.715424960098;10102.393856368348;10911.781764364827;11153.55385784592;11169.944150279196 13:30:41 INFO - PROCESS | 1674 | |3;mootools.html;1488.1220080152707;1556.2607761313377;1788.713805791073;1907.1322548240544;1950.4246247357028;8224.383171262149;8795.78010549735;8801.735947921565;9158.633654653806;9172.541372931351;8726.825463490726;8752.343574231942;8872.20150186483;8886.688965886195;8899.33255005874;2393.3418309964727;2434.1115492845074;2439.5852705040097;2441.5849305618;2449.0448724997264;2417.867017314047;2422.1764600035985;2444.2300675287315;2458.397692565324;2462.963055554168;2056.7531896172404;2211.900464479102;2430.5017471418405;2432.3067925641194;2438.500107477963;2417.830751847371;2421.225207933462;2423.2366804456715;2441.1700021992606;2459.2007597530874;1988.8508361872773;2207.5253820428684;2273.999440246286;2364.2552595932225;2415.830891837572;7790.5325680459355;8384.161583841567;8997.820043599157;9039.954800226022;9077.137671921157;5380.865478363072;5555.833325000256;5586.608937374385;5599.972000140014;5631.493165615114;3582.695470884978;3587.856485740567;3596.9100772480638;3720.0513868963512;3724.8696295629793;909.5179554835947;1007.2143727892255;1050.2385770316537;1056.5826498533113;1061.1298735037271;9981.750456238653;11715.707107322214;11833.230839995458;12249.387530623433;12257.877421225763;4836.492168322346;4929.75351232437;5007.799688012438;5008.924866127011;5011.799528018912;10314.329568578018;10385.688429346981;10387.584496620051;10391.220658450646;10391.84412233817;5526.530244929226;5534.972325138348;5577.274954256002;5603.271574695264;5622.718864056781;4047.4738284022887;4461.732296062247;4964.032013757354;5056.115179843586;5093.159628661304;4108.5275193352545;4141.819581419281;4161.855489740346;4169.3120635094865;4188.036751547101;1349.311850956;1360.7890776929592;1372.9038967272195;1385.6189547874415;1420.3679362683713;675.8175292670951;899.2266650680409;917.2295271971576;945.3193700535603;956.1490273656451;5645.802396916089;5931.9703401483575;5969.582129250997;6123.908141377883;6203.875922481525;904.2314033071967;991.3209451525728;1000.6197644895038;1005.5927349423495;1010.3028910052041;3843.442700808423;3860.4016377460975;3904.902377440587;3965.3060714375447;4000.959990400117;10383.792324153626;10851.131909447226;10933.45332733376;10958.671239862817;10960.12319014477;10833.970772776574;10956.945215273872;11002.50488728009;11022.228444008842;11088;8335.49987000782;8357.289630381363;8426.325893928471;8470.364722645827;8472.237498625153;5719.714014299185;5929.6145750526075;5932.614380065282;6085.087236914498;6172.660503672341;9799.363041402286;9801.362911410735;10088.092071713581;10113;10113.393196408239;10279.588816447558;10307.278490505592;10314.968503149625;10384.169266458513;10443.791124177626;6945.895811562932;7283.562986220843;7484.625768711434;7621.352185064332;7714;8422.578871056545;8438.957805210935;8450.957745211235;8510.446820956618;8512.233898949124;7475.588842613708;7586.393088553013;7665.195154508696;7715.996920400312;7719.575423351769;7424.888626670712;7481.139668937924;7507.549547027196;7563.130240022468;7614.466987310835;6028.728707208098;6894.724211031503;7067.752628657972;7069.081019467436;7107.715691372287;4216.7259128156575;4745.857624271277;4787.755183652206;4798.2802579613335;4912.92630610534;4131.256373852735;4161.063760653949;4925.753712314442;4981.626378021735;5033.5721463676 13:30:41 INFO - PROCESS | 1674 | |4;prototype.html;302.2775566396312;306.8941215280728;310.30646505061196;317.3874422364835;318.4331889237157;4812.494688057756;5185.922211166836;5320.547753440953;5424.484673955978;5430.53840423564;4883.755812209384;5434.91847622285;5439.619226654126;5459.044667183237;5482.985647655187;3828.5979972102946;3980.1840622672426;4165.687573431989;4201.235481097931;4215.66274698025;3801.771893686374;3902.2780785554764;3935.9803200983956;4205.179989901971;4216.241076606209;3963.135942456111;4062.5937406259286;4098.938515922264;4107.917841643165;4109.630133288004;3214.083986063972;3661.835217415223;3980.7014473914282;4110.671146308303;4148.709590328648;3530.452779819132;3569.5181150544604;3580.6240344763687;3581.224550973017;3588.4437912123662;4183.3515805050265;4341.978290108561;4943.752812359367;5054.545090941833;5307.840764777025;3778.659920607131;4675.667434781071;4704.388429504177;4710.8351207707565;4734.763261836929;4797.424309082898;5398.893226888478;5775.509081728058;5802.97098514509;5844.53243740504;3081.9691803081905;3283.917902052444;3730.552333719971;3897.7271590988644;3933.5476420211758;4775.832845850413;5024.924626130611;6006.549508786815;6044.24446944132;6112.63324200545;3932.3511620582854;3956.4460975463458;3973.324534829086;3973.8609148680234;3995.840166393312;3727;3817.255635151119;4035.6569691575955;4042;4044.393340998815;4543.227651299287;4563.748993805306;4652.395188625457;4671.15919134559;4715.434147902273;5464.754086066137;5832.387599302097;5835.315754752861;5846;5882.294154682522;1229.237872519044;1425.565202613213;1430.6623307207794;1457.8979471436899;1478.4012474947663;796.3867821777314;839.7228914458215;858.994975878224;956.6221342569646;970.9708708738749;4628.490866004804;5808.651480911074;5843.29880414353;5861.501772349313;5870.823875283749;5141.920196758648;5420.701861397582;6435.485161187094;6508.088867558546;6515.674216289167;4525.479569849444;5480.232767412565;5481.75332110056;5817.156512305776;5827.35899051104;3145.9528107078413;3406.284680217182;3407.931841363159;3414.436617958059;3422.19578399076;5735.942640573541;6168.136460895478;6218.5647004710145;6548.8362790930605;6779.220389655253;6321.1782468278825;6653.667316634149;6656.96671516649;6658.766943156967;6684.465242780566;5650.626736770566;5705.3169315052155;6517.641529715911;6812.625305608239;6903.620300883399;4896.877578060577;5695.971520142372;5728.513076388554;5741.799037033851;6502.967485162544;5383.380911195106;5640.774369025193;6384.872302554016;6400.359964003563;6453.354664533509;5693.544516438592;6094.299155597163;6701.262861085179;6763.7632682855865;6779.491538134561;4153.813078411418;4178.540360560275;4212.241796476664;4218.557051509547;4237.618614324726;4946.985867897;5106.62121227274;5390.04400137842;5949.970250148895;6500.25247096571;4892.339534162842;5742.856428589152;5750.645536681906;5760.539156867357;5801.477866991991;5709.34342550612;5756.568257380797;5763.337216220189;5773.566982476415;5788.27646544182;4379.781010949376;4954.752262386938;5608.663480191201;5688.203651488712;5793.768249269982;5093.898122037613;5484.8207635358585;5843.707814609167;5873;5883.9403510175325;4776.426828780569;5020.849374518771;5453.700046497481;5652.78465129999;5753 13:30:41 INFO - PROCESS | 1674 | |5;yui.html;591.633604788752;686.1491750229717;726.4224941171768;742.6546655805053;757.0990521279675;14553.78169327461;14895.851041489583;15064.322105505238;15099.018563793346;15149.091054536722;13714.519991800289;13714.862851371508;13745.969052321067;13752.831009364216;13760.174389536609;889.4929889962724;893.6827426263663;937.1846493550587;937.5921474158729;940.4827344960279;918.3892711346946;930.0234753508822;931.1852129386787;932.4871320773582;933.6358820060182;964.8215080210149;979.8187335342984;984.119213304096;985.0789511806448;987.2447577603103;836.2139588786551;974.7952929884733;975.1857199238644;975.8194733974167;976.7753416714124;709.6593635055151;721.1850608812061;726.1794172584998;726.4079774983371;727.4180655475651;15290.388384464608;15296.617584560363;15322.080675159526;15337.23313834315;15342.309596068204;12203.145779795417;12389.380530973505;12411.503539858395;12411.62765117048;12641.2415255085;9743.610255589769;9885.505724713807;10315.381077135396;10379.740506487322;10386.221033422524;3420.7263418926427;3547.3614749345106;3584.1577229351083;3607.783532988029;3672.797996110213;81749;82244.76632850512;83004.58497707473;83441.16558834395;83904.58047709783;9426.963034066248;9542.3320367575;9580.76048098803;9594.472304023206;9759.12167904892;16812.57968550796;20866.791332086486;20909.37271881846;21178.576428471653;21362.465938351666;7459.030326057577;7464.888026679604;7517.17311095779;7597.962010190025;7796.649150788228;4117.608827161415;4120.814563344657;4173.6661067114555;4184.184084103632;4301.892452688708;3116.6415862175686;3474.4961980512685;3521.3133438979153;3590.2101537661674;3601.1717305019993;595.7259660556106;607.6232735703892;609.6068036116679;613.6624856329001;613.9416755408229;573.9754538149383;574.5432381256911;584.1588113117098;592.0083859535262;612.0818771842237;8154.6738130475305;8185.222403871623;8337.916620833836;8357.791055223546;8493.830123397622;520.7812718658173;576.1760682224376;583.553581510145;583.8598736303342;584.3893131677407;3007.6691563927952;3189.091109033914;3795.3737633290198;3914.9554282075956;4128.8348466061625;90585.09414905899;96757;97941.04117917601;102966.45550316751;103106.48446757719;67285;78037.4392512158;78740;79316.60341698254;80947.59526202331;71197.2880271201;73240;73313;73378.63310683519;74160;7699.961500192463;7827.295543401121;7905.169957154416;7940.722074727357;8066.7579972600915;46479;46833.063338733715;47123.764381177876;47562.76218618885;47582.28626570674;64909.67545162244;66948.66525667341;68256.6587167061;68305;69052.65473672599;10009.449480278363;10156.746081348025;10161.136303414292;10202.795944081225;10534.104601108686;11126.221164518729;11169;11222.7755444889;11329.546818127183;11444.94277528607;10428.687139385831;10512.632057877938;10558.04977552024;10684;10791.730206744895;10369.274150809371;10515.737106572396;10537.73655658584;10632.25574209798;11018.834717479405;8452.957735211285;9974.551145198338;10281.434521101412;10601.681949541526;10777.191710621892;8905.287576993955;8923.643054277756;9086.727398178065;9232.215261702562;9279.39683920543;8936;8937.329700272376;9004.144606262395;9054.230390416626;9128.817423651622 13:30:41 INFO - PROCESS | 1674 | __end_tp_report 13:30:41 INFO - PROCESS | 1674 | __start_cc_report 13:30:41 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,418 13:30:41 INFO - PROCESS | 1674 | __end_cc_report 13:30:41 INFO - PROCESS | 1674 | __startTimestamp1461702641331__endTimestamp 13:30:41 INFO - PROCESS | 1674 | 13:30:41 INFO - PROCESS | 1674 | ------- Summary: start ------- 13:30:41 INFO - PROCESS | 1674 | Number of tests: 6 13:30:41 INFO - PROCESS | 1674 | 13:30:41 INFO - PROCESS | 1674 | [#0] dojo.html Cycles:165 Average:5686.63 Median:5915.38 stddev:692.25 (11.7%) stddev-sans-first:652.73 13:30:41 INFO - PROCESS | 1674 | Values: 2671.8 2864.6 3443.9 3487.8 3493.1 4150.4 4556.6 5543.0 5641.8 5666.5 5710.2 5717.7 5733.0 5736.8 5740.0 5145.3 5158.5 5235.1 5279.8 5320.8 4464.2 5120.6 5187.6 5248.2 5317.5 5270.5 5306.0 5323.4 5329.9 5349.3 5208.8 5256.2 5259.5 5269.8 5295.7 5072.8 5106.1 5142.7 5211.0 5225.6 5492.3 5520.8 5552.5 5603.3 5653.2 5088.6 5179.4 6121.8 6187.9 6293.9 6157.3 6161.1 6206.5 6250.6 6279.8 5835.5 5891.9 5915.4 5918.9 5923.8 5266.0 5278.4 6253.9 6342.7 6363.2 5069.9 6059.8 6117.9 6228.1 6236.9 6273.8 6284.6 6292.1 6314.9 6328.5 6156.9 6169.7 6241.6 6275.7 6295.5 5497.8 6050.4 6094.9 6306.2 6309.3 4933.4 4933.5 4946.6 4948.3 4952.4 4367.7 4451.2 4467.4 4469.4 4470.3 5172.6 5569.2 5652.2 5708.2 5940.7 6193.3 6239.4 6246.2 6246.4 6250.6 5636.3 5861.0 5877.7 5895.1 5935.1 5412.3 5455.0 5480.4 5487.0 5534.5 6390.3 6418.8 6425.7 6434.9 6442.6 5031.9 6196.0 6265.2 6268.2 6291.1 6356.2 6409.5 6409.9 6410.9 6413.6 6213.1 6259.9 6263.7 6266.9 6282.6 5262.4 6116.3 6164.5 6171.9 6206.0 6014.4 6081.5 6117.2 6134.5 6170.2 5450.2 6171.1 6179.6 6199.3 6225.6 5479.6 5757.7 5961.4 6038.6 6047.2 5849.4 6078.2 6093.6 6120.4 6126.1 6020.9 6048.0 6051.1 6070.0 6097.2 13:30:41 INFO - PROCESS | 1674 | 13:30:41 INFO - PROCESS | 1674 | [#1] ext.html Cycles:180 Average:64350.59 Median:210631.12 stddev:142158.76 (67.5%) stddev-sans-first:142476.75 13:30:41 INFO - PROCESS | 1674 | Values: 512.3 524.4 583.7 614.2 624.8 39492.2 40648.6 40668.8 40675.6 40685.8 41624.6 41840.6 42352.9 42356.4 42664.0 9351.0 9589.4 9596.2 9598.9 9623.7 8501.0 9518.1 9829.9 9846.4 9852.8 9808.7 9829.7 9859.3 9910.4 9920.9 9747.6 9766.3 9817.4 9844.2 9851.8 9603.2 9653.5 9728.0 9732.8 9753.5 41385.8 41643.4 41917.6 41953.6 42043.2 2764.6 3043.2 3133.5 3159.2 3296.9 26711.1 26737.5 27513.3 27839.0 28185.7 2672.4 2698.3 2720.9 2779.3 2827.6 377292.0 380056.0 380495.0 380855.0 382850.0 35302.0 35869.3 36191.3 36376.3 36387.6 35812.6 36189.3 36268.9 36349.6 36529.1 15816.7 16060.1 16084.1 16108.8 16121.5 13447.0 13485.8 13507.2 13509.5 13591.5 8238.0 8351.5 8392.3 8450.8 8453.8 3410.4 3415.8 3470.4 3474.9 3476.9 960.8 1082.0 1101.6 1104.4 1151.2 22630.7 30559.5 31144.7 31188.2 31729.9 1042.7 1086.3 1089.1 1101.4 1127.1 11550.7 12259.8 15771.2 15955.3 16211.6 670486.0 680603.0 688316.0 688502.0 688927.0 63501.0 63929.4 64387.7 64529.7 64684.0 1476.2 1505.6 1521.8 1522.5 1527.6 25883.9 30527.8 30885.7 31008.7 31511.0 56550.7 57169.0 57405.7 57594.4 57762.1 480917.0 481451.0 482467.0 482998.0 483251.0 28009.0 28209.0 28271.3 28390.3 28617.9 48017.0 48213.8 48624.3 48685.3 49794.0 39244.0 39824.8 39848.0 40259.4 40350.6 32618.0 39531.4 39554.8 39911.4 40435.6 32531.5 38412.2 38746.8 38972.2 39227.0 30751.4 32576.8 33306.0 34376.8 34507.0 25157.4 25337.0 25692.6 25820.7 25908.9 13:30:41 INFO - PROCESS | 1674 | 13:30:41 INFO - PROCESS | 1674 | [#2] jquery.html Cycles:180 Average:36016.73 Median:11912.71 stddev:101924.97 (855.6%) stddev-sans-first:102175.80 13:30:41 INFO - PROCESS | 1674 | Values: 392.3 394.5 399.9 401.4 403.8 20002.6 21019.5 21129.9 21291.6 22634.8 21325.9 21352.0 21638.4 22229.7 22281.0 5469.6 6084.6 6094.9 6115.7 6119.9 4988.7 5913.9 6026.0 6064.2 6131.6 5597.9 5939.2 5956.6 5967.2 5984.7 5824.0 5886.9 5940.6 5969.6 5992.2 5240.9 5250.5 5264.3 5273.0 5285.2 12980.0 13730.2 25742.0 26633.2 26769.0 2129.9 2187.1 2230.1 2347.5 2497.9 9768.0 10230.0 10326.0 13576.0 13745.6 1694.2 1727.3 1737.6 1763.8 1784.9 123752.0 125284.0 126966.4 129028.4 130440.0 11951.3 12034.4 12097.1 12113.2 12151.8 11785.4 12725.3 12807.9 15423.3 15533.8 7832.9 7944.2 9099.0 9124.9 9205.1 8975.9 8992.1 9053.0 9065.7 9129.7 4171.2 4559.7 4723.2 4780.5 4814.4 1946.8 1991.5 2021.6 2023.1 2058.5 745.3 954.5 989.9 1006.3 1010.5 20358.2 21019.3 21058.8 21126.6 21483.7 845.9 945.2 968.4 1033.2 1070.5 8241.4 10984.6 11265.4 11299.6 11307.7 585545.0 601239.0 605378.0 621941.0 622721.0 134871.3 138183.3 139970.3 140932.0 143714.3 1000.9 1051.6 1058.3 1058.9 1060.5 18313.3 18544.5 18765.3 18918.7 19036.3 50519.0 56309.2 56342.7 57142.0 57241.4 79629.0 83190.2 83404.0 84050.6 85022.7 7961.9 8160.6 8218.3 8225.1 8230.3 11681.2 12256.3 12486.7 12505.6 12635.8 10784.9 10791.2 10841.2 10867.8 10955.5 10628.4 10705.1 10722.1 11028.4 11038.2 10793.9 11047.7 11253.5 11284.4 11336.0 8068.9 8079.0 8161.7 8188.9 8248.5 8130.7 10102.4 10911.8 11153.6 11169.9 13:30:41 INFO - PROCESS | 1674 | 13:30:41 INFO - PROCESS | 1674 | [#3] mootools.html Cycles:180 Average:5787.85 Median:4793.02 stddev:3248.75 (67.8%) stddev-sans-first:3241.79 13:30:41 INFO - PROCESS | 1674 | Values: 1488.1 1556.3 1788.7 1907.1 1950.4 8224.4 8795.8 8801.7 9158.6 9172.5 8726.8 8752.3 8872.2 8886.7 8899.3 2393.3 2434.1 2439.6 2441.6 2449.0 2417.9 2422.2 2444.2 2458.4 2463.0 2056.8 2211.9 2430.5 2432.3 2438.5 2417.8 2421.2 2423.2 2441.2 2459.2 1988.9 2207.5 2274.0 2364.3 2415.8 7790.5 8384.2 8997.8 9040.0 9077.1 5380.9 5555.8 5586.6 5600.0 5631.5 3582.7 3587.9 3596.9 3720.1 3724.9 909.5 1007.2 1050.2 1056.6 1061.1 9981.8 11715.7 11833.2 12249.4 12257.9 4836.5 4929.8 5007.8 5008.9 5011.8 10314.3 10385.7 10387.6 10391.2 10391.8 5526.5 5535.0 5577.3 5603.3 5622.7 4047.5 4461.7 4964.0 5056.1 5093.2 4108.5 4141.8 4161.9 4169.3 4188.0 1349.3 1360.8 1372.9 1385.6 1420.4 675.8 899.2 917.2 945.3 956.1 5645.8 5932.0 5969.6 6123.9 6203.9 904.2 991.3 1000.6 1005.6 1010.3 3843.4 3860.4 3904.9 3965.3 4001.0 10383.8 10851.1 10933.5 10958.7 10960.1 10834.0 10956.9 11002.5 11022.2 11088.0 8335.5 8357.3 8426.3 8470.4 8472.2 5719.7 5929.6 5932.6 6085.1 6172.7 9799.4 9801.4 10088.1 10113.0 10113.4 10279.6 10307.3 10315.0 10384.2 10443.8 6945.9 7283.6 7484.6 7621.4 7714.0 8422.6 8439.0 8451.0 8510.4 8512.2 7475.6 7586.4 7665.2 7716.0 7719.6 7424.9 7481.1 7507.5 7563.1 7614.5 6028.7 6894.7 7067.8 7069.1 7107.7 4216.7 4745.9 4787.8 4798.3 4912.9 4131.3 4161.1 4925.8 4981.6 5033.6 13:30:41 INFO - PROCESS | 1674 | 13:30:41 INFO - PROCESS | 1674 | [#4] prototype.html Cycles:180 Average:4726.79 Median:5163.92 stddev:1511.15 (29.3%) stddev-sans-first:1478.44 13:30:41 INFO - PROCESS | 1674 | Values: 302.3 306.9 310.3 317.4 318.4 4812.5 5185.9 5320.5 5424.5 5430.5 4883.8 5434.9 5439.6 5459.0 5483.0 3828.6 3980.2 4165.7 4201.2 4215.7 3801.8 3902.3 3936.0 4205.2 4216.2 3963.1 4062.6 4098.9 4107.9 4109.6 3214.1 3661.8 3980.7 4110.7 4148.7 3530.5 3569.5 3580.6 3581.2 3588.4 4183.4 4342.0 4943.8 5054.5 5307.8 3778.7 4675.7 4704.4 4710.8 4734.8 4797.4 5398.9 5775.5 5803.0 5844.5 3082.0 3283.9 3730.6 3897.7 3933.5 4775.8 5024.9 6006.5 6044.2 6112.6 3932.4 3956.4 3973.3 3973.9 3995.8 3727.0 3817.3 4035.7 4042.0 4044.4 4543.2 4563.7 4652.4 4671.2 4715.4 5464.8 5832.4 5835.3 5846.0 5882.3 1229.2 1425.6 1430.7 1457.9 1478.4 796.4 839.7 859.0 956.6 971.0 4628.5 5808.7 5843.3 5861.5 5870.8 5141.9 5420.7 6435.5 6508.1 6515.7 4525.5 5480.2 5481.8 5817.2 5827.4 3146.0 3406.3 3407.9 3414.4 3422.2 5735.9 6168.1 6218.6 6548.8 6779.2 6321.2 6653.7 6657.0 6658.8 6684.5 5650.6 5705.3 6517.6 6812.6 6903.6 4896.9 5696.0 5728.5 5741.8 6503.0 5383.4 5640.8 6384.9 6400.4 6453.4 5693.5 6094.3 6701.3 6763.8 6779.5 4153.8 4178.5 4212.2 4218.6 4237.6 4947.0 5106.6 5390.0 5950.0 6500.3 4892.3 5742.9 5750.6 5760.5 5801.5 5709.3 5756.6 5763.3 5773.6 5788.3 4379.8 4954.8 5608.7 5688.2 5793.8 5093.9 5484.8 5843.7 5873.0 5883.9 4776.4 5020.8 5453.7 5652.8 5753.0 13:30:41 INFO - PROCESS | 1674 | 13:30:41 INFO - PROCESS | 1674 | [#5] yui.html Cycles:180 Average:18288.28 Median:610.84 stddev:26390.10 (4320.3%) stddev-sans-first:26430.68 13:30:41 INFO - PROCESS | 1674 | Values: 591.6 686.1 726.4 742.7 757.1 14553.8 14895.9 15064.3 15099.0 15149.1 13714.5 13714.9 13746.0 13752.8 13760.2 889.5 893.7 937.2 937.6 940.5 918.4 930.0 931.2 932.5 933.6 964.8 979.8 984.1 985.1 987.2 836.2 974.8 975.2 975.8 976.8 709.7 721.2 726.2 726.4 727.4 15290.4 15296.6 15322.1 15337.2 15342.3 12203.1 12389.4 12411.5 12411.6 12641.2 9743.6 9885.5 10315.4 10379.7 10386.2 3420.7 3547.4 3584.2 3607.8 3672.8 81749.0 82244.8 83004.6 83441.2 83904.6 9427.0 9542.3 9580.8 9594.5 9759.1 16812.6 20866.8 20909.4 21178.6 21362.5 7459.0 7464.9 7517.2 7598.0 7796.6 4117.6 4120.8 4173.7 4184.2 4301.9 3116.6 3474.5 3521.3 3590.2 3601.2 595.7 607.6 609.6 613.7 613.9 574.0 574.5 584.2 592.0 612.1 8154.7 8185.2 8337.9 8357.8 8493.8 520.8 576.2 583.6 583.9 584.4 3007.7 3189.1 3795.4 3915.0 4128.8 90585.1 96757.0 97941.0 102966.5 103106.5 67285.0 78037.4 78740.0 79316.6 80947.6 71197.3 73240.0 73313.0 73378.6 74160.0 7700.0 7827.3 7905.2 7940.7 8066.8 46479.0 46833.1 47123.8 47562.8 47582.3 64909.7 66948.7 68256.7 68305.0 69052.7 10009.4 10156.7 10161.1 10202.8 10534.1 11126.2 11169.0 11222.8 11329.5 11444.9 10428.7 10512.6 10558.0 10684.0 10791.7 10369.3 10515.7 10537.7 10632.3 11018.8 8453.0 9974.6 10281.4 10601.7 10777.2 8905.3 8923.6 9086.7 9232.2 9279.4 8936.0 8937.3 9004.1 9054.2 9128.8 13:30:41 INFO - PROCESS | 1674 | -------- Summary: end -------- 13:30:41 INFO - PROCESS | 1674 | 13:30:41 INFO - TEST-INFO | 1674: exit 0 13:30:41 INFO - TEST-OK | dromaeo_css | took 1110727ms 13:30:41 INFO - TEST-START | kraken 13:30:41 INFO - operating with platform_type : mac_ 13:30:41 INFO - Initialising browser for kraken test... 13:30:41 INFO - TEST-INFO | started process 1682 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/tmpTMMh_7/profile http://localhost:49283/getInfo.html) 13:30:44 INFO - PROCESS | 1682 | __metrics Screen width/height:1600/1200 13:30:44 INFO - PROCESS | 1682 | colorDepth:24 13:30:44 INFO - PROCESS | 1682 | Browser inner width/height: 1024/689 13:30:44 INFO - PROCESS | 1682 | __metrics 13:30:44 ERROR - PROCESS | 1682 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 13:30:45 INFO - TEST-INFO | 1682: exit 0 13:30:45 INFO - Browser initialized. 13:30:45 INFO - Running cycle 1/1 for kraken test... 13:30:45 INFO - TEST-INFO | started process 1685 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/tmpTMMh_7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:30:57 INFO - PROCESS | 1685 | RSS: Main: 221605888 13:30:57 INFO - PROCESS | 1685 | 13:31:00 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-beat-detection) 13:31:00 INFO - PROCESS | 1685 | RSS: Main: 233762816 13:31:00 INFO - PROCESS | 1685 | 13:31:03 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-dft) 13:31:04 INFO - PROCESS | 1685 | RSS: Main: 243064832 13:31:04 INFO - PROCESS | 1685 | 13:31:07 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-fft) 13:31:08 INFO - PROCESS | 1685 | RSS: Main: 246657024 13:31:08 INFO - PROCESS | 1685 | 13:31:10 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-oscillator) 13:31:10 INFO - PROCESS | 1685 | RSS: Main: 247767040 13:31:10 INFO - PROCESS | 1685 | 13:31:12 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49283/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:31:13 INFO - PROCESS | 1685 | RSS: Main: 247971840 13:31:13 INFO - PROCESS | 1685 | 13:31:16 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49283/tests/kraken/driver.html?testName=imaging-darkroom) 13:31:17 INFO - PROCESS | 1685 | RSS: Main: 248832000 13:31:17 INFO - PROCESS | 1685 | 13:31:20 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49283/tests/kraken/driver.html?testName=imaging-desaturate) 13:31:20 INFO - PROCESS | 1685 | RSS: Main: 248926208 13:31:20 INFO - PROCESS | 1685 | 13:31:23 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49283/tests/kraken/driver.html?testName=json-parse-financial) 13:31:24 INFO - PROCESS | 1685 | RSS: Main: 249122816 13:31:24 INFO - PROCESS | 1685 | 13:31:26 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49283/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:31:26 INFO - PROCESS | 1685 | RSS: Main: 248090624 13:31:26 INFO - PROCESS | 1685 | 13:31:28 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:31:29 INFO - PROCESS | 1685 | RSS: Main: 248270848 13:31:29 INFO - PROCESS | 1685 | 13:31:31 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:31:32 INFO - PROCESS | 1685 | RSS: Main: 248532992 13:31:32 INFO - PROCESS | 1685 | 13:31:35 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:31:35 INFO - PROCESS | 1685 | RSS: Main: 248594432 13:31:35 INFO - PROCESS | 1685 | 13:31:38 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:31:39 INFO - PROCESS | 1685 | RSS: Main: 248504320 13:31:39 INFO - PROCESS | 1685 | 13:31:41 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49283/tests/kraken/driver.html?testName=ai-astar) 13:31:41 INFO - PROCESS | 1685 | RSS: Main: 250200064 13:31:41 INFO - PROCESS | 1685 | 13:31:41 INFO - PROCESS | 1685 | __start_tp_report 13:31:41 INFO - PROCESS | 1685 | _x_x_mozilla_page_load 13:31:41 INFO - PROCESS | 1685 | _x_x_mozilla_page_load_details 13:31:41 INFO - PROCESS | 1685 | |i|pagename|runs| 13:31:41 INFO - PROCESS | 1685 | |0;ai-astar;159;165;167;121;117;132;117;114;117;120 13:31:41 INFO - PROCESS | 1685 | |1;audio-beat-detection;154;123;183;159;121;140;143;122;184;156 13:31:41 INFO - PROCESS | 1685 | |2;audio-dft;203;199;200;197;198;246;225;196;201;201 13:31:41 INFO - PROCESS | 1685 | |3;audio-fft;72;78;75;149;58;58;56;63;132;56 13:31:41 INFO - PROCESS | 1685 | |4;audio-oscillator;82;88;129;77;79;87;123;81;80;121 13:31:41 INFO - PROCESS | 1685 | |5;imaging-gaussian-blur;136;89;91;89;91;88;92;89;92;90 13:31:41 INFO - PROCESS | 1685 | |6;imaging-darkroom;95;97;97;95;97;128;147;101;126;102 13:31:41 INFO - PROCESS | 1685 | |7;imaging-desaturate;92;93;91;125;94;88;76;115;146;147 13:31:41 INFO - PROCESS | 1685 | |8;json-parse-financial;63;66;67;66;68;65;68;81;64;61 13:31:41 INFO - PROCESS | 1685 | |9;json-stringify-tinderbox;67;68;70;73;74;71;73;66;69;68 13:31:41 INFO - PROCESS | 1685 | |10;stanford-crypto-aes;81;85;90;68;99;73;105;105;113;85 13:31:41 INFO - PROCESS | 1685 | |11;stanford-crypto-ccm;135;139;135;136;138;136;161;156;194;149 13:31:41 INFO - PROCESS | 1685 | |12;stanford-crypto-pbkdf2;173;184;170;182;234;176;171;170;171;228 13:31:41 INFO - PROCESS | 1685 | |13;stanford-crypto-sha256-iterative;71;68;71;70;68;68;68;66;69;70 13:31:41 INFO - PROCESS | 1685 | __end_tp_report 13:31:41 INFO - PROCESS | 1685 | __start_cc_report 13:31:41 INFO - PROCESS | 1685 | _x_x_mozilla_cycle_collect,1111 13:31:41 INFO - PROCESS | 1685 | __end_cc_report 13:31:41 INFO - PROCESS | 1685 | __startTimestamp1461702701281__endTimestamp 13:31:41 INFO - PROCESS | 1685 | 13:31:41 INFO - PROCESS | 1685 | ------- Summary: start ------- 13:31:41 INFO - PROCESS | 1685 | Number of tests: 14 13:31:41 INFO - PROCESS | 1685 | 13:31:41 INFO - PROCESS | 1685 | [#0] ai-astar Cycles:10 Average:132.90 Median:126.50 stddev:21.86 (17.3%) stddev-sans-first:21.04 13:31:41 INFO - PROCESS | 1685 | Values: 159.0 165.0 167.0 121.0 117.0 132.0 117.0 114.0 117.0 120.0 13:31:41 INFO - PROCESS | 1685 | 13:31:41 INFO - PROCESS | 1685 | [#1] audio-beat-detection Cycles:10 Average:148.50 Median:155.00 stddev:23.23 (15.0%) stddev-sans-first:24.56 13:31:41 INFO - PROCESS | 1685 | Values: 154.0 123.0 183.0 159.0 121.0 140.0 143.0 122.0 184.0 156.0 13:31:41 INFO - PROCESS | 1685 | 13:31:41 INFO - PROCESS | 1685 | [#2] audio-dft Cycles:10 Average:206.60 Median:201.00 stddev:16.15 (8.0%) stddev-sans-first:17.07 13:31:41 INFO - PROCESS | 1685 | Values: 203.0 199.0 200.0 197.0 198.0 246.0 225.0 196.0 201.0 201.0 13:31:41 INFO - PROCESS | 1685 | 13:31:41 INFO - PROCESS | 1685 | [#3] audio-fft Cycles:10 Average:79.70 Median:60.50 stddev:33.28 (55.0%) stddev-sans-first:35.18 13:31:41 INFO - PROCESS | 1685 | Values: 72.0 78.0 75.0 149.0 58.0 58.0 56.0 63.0 132.0 56.0 13:31:41 INFO - PROCESS | 1685 | 13:31:41 INFO - PROCESS | 1685 | [#4] audio-oscillator Cycles:10 Average:94.70 Median:80.50 stddev:20.81 (25.9%) stddev-sans-first:21.56 13:31:41 INFO - PROCESS | 1685 | Values: 82.0 88.0 129.0 77.0 79.0 87.0 123.0 81.0 80.0 121.0 13:31:41 INFO - PROCESS | 1685 | 13:31:41 INFO - PROCESS | 1685 | [#5] imaging-gaussian-blur Cycles:10 Average:94.70 Median:90.50 stddev:14.58 (16.1%) stddev-sans-first:1.45 13:31:41 INFO - PROCESS | 1685 | Values: 136.0 89.0 91.0 89.0 91.0 88.0 92.0 89.0 92.0 90.0 13:31:41 INFO - PROCESS | 1685 | 13:31:41 INFO - PROCESS | 1685 | [#6] imaging-darkroom Cycles:10 Average:108.50 Median:95.00 stddev:18.34 (19.3%) stddev-sans-first:18.79 13:31:41 INFO - PROCESS | 1685 | Values: 95.0 97.0 97.0 95.0 97.0 128.0 147.0 101.0 126.0 102.0 13:31:41 INFO - PROCESS | 1685 | 13:31:41 INFO - PROCESS | 1685 | [#7] imaging-desaturate Cycles:10 Average:106.70 Median:89.50 stddev:25.11 (28.1%) stddev-sans-first:26.07 13:31:41 INFO - PROCESS | 1685 | Values: 92.0 93.0 91.0 125.0 94.0 88.0 76.0 115.0 146.0 147.0 13:31:41 INFO - PROCESS | 1685 | 13:31:41 INFO - PROCESS | 1685 | [#8] json-parse-financial Cycles:10 Average:66.90 Median:66.50 stddev:5.43 (8.2%) stddev-sans-first:5.57 13:31:41 INFO - PROCESS | 1685 | Values: 63.0 66.0 67.0 66.0 68.0 65.0 68.0 81.0 64.0 61.0 13:31:41 INFO - PROCESS | 1685 | 13:31:41 INFO - PROCESS | 1685 | [#9] json-stringify-tinderbox Cycles:10 Average:69.90 Median:70.50 stddev:2.77 (3.9%) stddev-sans-first:2.73 13:31:41 INFO - PROCESS | 1685 | Values: 67.0 68.0 70.0 73.0 74.0 71.0 73.0 66.0 69.0 68.0 13:31:41 INFO - PROCESS | 1685 | 13:31:41 INFO - PROCESS | 1685 | [#10] stanford-crypto-aes Cycles:10 Average:90.40 Median:83.00 stddev:14.77 (17.8%) stddev-sans-first:15.27 13:31:41 INFO - PROCESS | 1685 | Values: 81.0 85.0 90.0 68.0 99.0 73.0 105.0 105.0 113.0 85.0 13:31:41 INFO - PROCESS | 1685 | 13:31:41 INFO - PROCESS | 1685 | [#11] stanford-crypto-ccm Cycles:10 Average:147.90 Median:144.00 stddev:18.73 (13.0%) stddev-sans-first:19.27 13:31:41 INFO - PROCESS | 1685 | Values: 135.0 139.0 135.0 136.0 138.0 136.0 161.0 156.0 194.0 149.0 13:31:41 INFO - PROCESS | 1685 | 13:31:41 INFO - PROCESS | 1685 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.90 Median:179.00 stddev:24.31 (13.6%) stddev-sans-first:25.33 13:31:41 INFO - PROCESS | 1685 | Values: 173.0 184.0 170.0 182.0 234.0 176.0 171.0 170.0 171.0 228.0 13:31:41 INFO - PROCESS | 1685 | 13:31:41 INFO - PROCESS | 1685 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.90 Median:69.50 stddev:1.60 (2.3%) stddev-sans-first:1.50 13:31:41 INFO - PROCESS | 1685 | Values: 71.0 68.0 71.0 70.0 68.0 68.0 68.0 66.0 69.0 70.0 13:31:41 INFO - PROCESS | 1685 | -------- Summary: end -------- 13:31:41 INFO - PROCESS | 1685 | 13:31:42 INFO - TEST-INFO | 1685: exit 0 13:31:42 INFO - TEST-OK | kraken | took 60221ms 13:31:42 INFO - SUITE-END | took 1170s 13:31:42 INFO - Completed test suite (00:19:31) 13:31:42 INFO - javascript benchmark 13:31:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2671.826331288466, 2864.64191976003, 3443.8622455101795, 3487.80817055062, 3493.074335301144, 4150.377443383494, 4556.567126123013, 5543.029969755292, 5641.774329026839, 5666.546676265899, 5710.200571919924, 5717.656940583568, 5733.0, 5736.770529178828, 5739.971300143494, 5145.3053837731895, 5158.458361872005, 5235.057689615867, 5279.788808447676, 5320.787168513274, 4464.196444639963, 5120.590352771769, 5187.584993200535, 5248.186531087668, 5317.494837990404, 5270.4729527047375, 5306.018386598473, 5323.387810401815, 5329.946700533003, 5349.304590403242, 5208.791648334062, 5256.237845512398, 5259.526642602146, 5269.789208431658, 5295.735213239323, 5072.822451214227, 5106.106431374532, 5142.665726727751, 5211.0359583477175, 5225.555827754645, 5492.258545096401, 5520.751566179531, 5552.472515111058, 5603.2715746953045, 5653.236813030229, 5088.643794934356, 5179.404368497633, 6121.846953826145, 6187.938120618781, 6293.87412251757, 6157.322694503601, 6161.075838624198, 6206.503479721611, 6250.562460627758, 6279.780207692755, 5835.533157347402, 5891.911621325684, 5915.3788852170765, 5918.852028699274, 5923.822285331447, 5265.9736701317015, 5278.366596008426, 6253.874922501524, 6342.746290148435, 6363.172787537591, 5069.8732531687, 6059.818205453844, 6117.888303246575, 6228.128062071313, 6236.875262494816, 6273.811785646438, 6284.5600807943, 6292.087647291209, 6314.873702526015, 6328.493720502349, 6156.907646385308, 6169.722362493699, 6241.563090583706, 6275.748970041239, 6295.464885484785, 5497.8075767348755, 6050.42520960508, 6094.939050609437, 6306.211723534559, 6309.274433440121, 4933.358663373739, 4933.506649335038, 4946.604271658259, 4948.331975183376, 4952.356193694796, 4367.737935723867, 4451.221036318631, 4467.352233926127, 4469.351943968107, 4470.307102399133, 5172.612054095958, 5569.220309156722, 5652.152177173457, 5708.229389032511, 5940.70296485174, 6193.349698281616, 6239.438450539473, 6246.219222597176, 6246.4378205960775, 6250.562460627803, 5636.323641163005, 5861.0, 5877.715574449421, 5895.115732640138, 5935.109733539917, 5412.296401467784, 5455.0, 5480.3971563128025, 5486.972565137149, 5534.52956498694, 6390.265119511316, 6418.775342862978, 6425.74297028123, 6434.935650643433, 6442.645654489048, 5031.874203144951, 6196.0, 6265.248170219603, 6268.216472940882, 6291.1192433058495, 6356.173697419306, 6409.4872410206135, 6409.9359006411205, 6410.935890641033, 6413.615183089029, 6213.099100630474, 6259.906101408574, 6263.655498947602, 6266.905996410149, 6282.560220784501, 5262.447443018428, 6116.296625887901, 6164.537659675632, 6171.876562468636, 6206.038064100072, 6014.428629280212, 6081.483073938764, 6117.204763380732, 6134.528431832502, 6170.197874276315, 5450.209719590558, 6171.13604095418, 6179.567430279837, 6199.349068347939, 6225.62646241227, 5479.630092476881, 5757.65454072757, 5961.433663801932, 6038.63768173911, 6047.213862197887, 5849.415058494116, 6078.179445774941, 6093.634381937098, 6120.418560236771, 6126.08108783686, 6020.939790602213, 6047.969760151171, 6051.062085376773, 6069.969650151721, 6097.207363042776], "value": 5642.553302281709, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [512.2955935588566, 524.4178961352895, 583.6818933681149, 614.1555361378105, 624.821925751161, 39492.2101557969, 40648.59351406485, 40668.79665601672, 40675.59324406755, 40685.77942661718, 41624.58375416245, 41840.58159418405, 42352.9411764706, 42356.3646545302, 42664.0, 9351.01814309498, 9589.37669051513, 9596.184324332406, 9598.904010959906, 9623.711288661352, 8501.0, 9518.14336709696, 9829.852552211723, 9846.40921544706, 9852.802943941117, 9808.705738827812, 9829.705108846745, 9859.30984831062, 9910.355826871266, 9920.900790992106, 9747.561359738758, 9766.316357854917, 9817.410955342702, 9844.212463002943, 9851.753706157331, 9603.18372938301, 9653.517324133765, 9728.027197280286, 9732.75668108296, 9753.463559504224, 41385.79307103475, 41643.37534936978, 41917.58082419198, 41953.58046419527, 42043.1591368174, 2764.6129541864157, 3043.239190202449, 3133.4516459619704, 3159.210197450637, 3296.912019033713, 26711.125309480067, 26737.465250694877, 27513.31216719578, 27839.0, 28185.718142818514, 2672.398710290189, 2698.298442404969, 2720.9455810883674, 2779.2608263935113, 2827.5758636204528, 377292.0, 380056.0, 380495.0, 380855.0, 382850.0, 35302.0, 35869.28261434809, 36191.27617447636, 36376.27247455036, 36387.63612363896, 35812.64187358145, 36189.27621447556, 36268.91193264206, 36349.636503634625, 36529.086772830895, 15816.68366632661, 16060.116693581731, 16084.115373654562, 16108.75836862448, 16121.516354509384, 13447.0, 13485.797713034312, 13507.189568625914, 13509.459621615222, 13591.456341746418, 8238.011438627283, 8351.498910065415, 8392.286655634218, 8450.788730281793, 8453.830923381498, 3410.386130496485, 3415.838614870956, 3470.410030294856, 3474.913127171841, 3476.89569312921, 960.8270511308032, 1081.9621313254, 1101.5924108080062, 1104.3981030338325, 1151.240181480219, 22630.66054009191, 30559.541606875915, 31144.688553114178, 31188.220294492818, 31729.889453869004, 1042.723678225271, 1086.3481910853582, 1089.1069323154936, 1101.363073110285, 1127.143371038017, 11550.711232219262, 12259.754804903852, 15771.211439427983, 15955.282012309473, 16211.594710132105, 670486.0, 680603.0, 688316.0, 688502.0, 688927.0, 63501.047484287774, 63929.36070639327, 64387.67806160939, 64529.67735161388, 64684.0, 1476.2102275282887, 1505.646173149331, 1521.8402067782945, 1522.5204060720837, 1527.633367991674, 25883.87058064773, 30527.847360763055, 30885.691143088283, 31008.68991310058, 31511.0, 56550.71724641351, 57169.0, 57405.71297143488, 57594.42405576058, 57762.133567997356, 480917.0, 481451.0, 482467.0, 482998.0, 483251.0, 28009.019684311766, 28209.012684555943, 28271.29321766972, 28390.290242744097, 28617.856910715313, 48017.0, 48213.794655133905, 48624.27063593978, 48685.26972095492, 49794.0, 39244.0, 39824.80087599543, 39848.0, 40259.39610905897, 40350.58772942933, 32618.0, 39531.40702889516, 39554.802225988686, 39911.40132897951, 40435.595644043184, 32531.512027319135, 38412.23175536418, 38746.80626596849, 38972.220555589294, 39227.0, 30751.384972300875, 32576.83711581427, 33306.00081997544, 34376.82811585926, 34507.0, 25157.371065723502, 25337.0, 25692.614610781227, 25820.741792581834, 25908.870455648354], "value": 18551.874015001784, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.28016589558143, 394.46155997064005, 399.9080211551343, 401.44800898764197, 403.78397557306846, 20002.59994800105, 21019.528632995676, 21129.894350528244, 21291.574168516658, 22634.773652263513, 21325.893370533126, 21352.0, 21638.3508494745, 22229.666555001688, 22281.0, 5469.617126801136, 6084.634921904678, 6094.908576371358, 6115.69421528924, 6119.877602447948, 4988.700677959316, 5913.9059274034225, 6025.969870150643, 6064.241969753781, 6131.632102073868, 5597.888042239153, 5939.168516407706, 5956.55325850563, 5967.194428752128, 5984.670843103628, 5823.951688696033, 5886.911696324559, 5940.584159108864, 5969.641821490724, 5992.161097446361, 5240.895182096337, 5250.501202385767, 5264.315638966948, 5273.0, 5285.233641122016, 12980.026498012592, 13730.244836533986, 25742.0, 26633.201003969913, 26769.0, 2129.9148034078617, 2187.0704950396016, 2230.14139556272, 2347.507023525062, 2497.8751062446986, 9768.0, 10230.028147325993, 10326.0, 13575.981801364936, 13745.587632371045, 1694.2121913310248, 1727.3349760342232, 1737.5569229846308, 1763.8147994460653, 1784.8929064256056, 123752.0, 125284.0, 126966.3651681745, 129028.35485822604, 130440.0, 11951.282923024646, 12034.398280085961, 12097.092718046006, 12113.212641178296, 12151.75696486083, 11785.3518056506, 12725.300108493937, 12807.871921280854, 15423.305951232222, 15533.844661553467, 7832.882506762289, 7944.205579442009, 9099.0, 9124.908750912406, 9205.125513076247, 8975.865362019575, 8992.145746154105, 9053.004169541346, 9065.728028159034, 9129.726108216764, 4171.186618609342, 4559.658025648089, 4723.244280915037, 4780.498047704941, 4814.398200224972, 1946.8247857692875, 1991.4822146242082, 2021.6158929803291, 2023.089609675652, 2058.475088852363, 745.2956955676833, 954.4893481987103, 989.8614194012844, 1006.3408467453829, 1010.5099026971913, 20358.18567257293, 21019.264325748525, 21058.78941210599, 21126.577468450545, 21483.67774483384, 845.8942632170979, 945.239082538563, 968.4189486308159, 1033.1682995188944, 1070.4701172919454, 8241.423100382915, 10984.615538456275, 11265.380404077854, 11299.604513841976, 11307.717307067225, 585545.0, 601239.0, 605378.0, 621941.0, 622721.0, 134871.32564337115, 138183.30908345393, 139970.30014850065, 140932.0, 143714.2814285943, 1000.9199264058858, 1051.5793682526898, 1058.2856571813995, 1058.915286777056, 1060.5068643081004, 18313.267469301078, 18544.536386590444, 18765.34321298748, 18918.716219256996, 19036.33372831944, 50519.0, 56309.15536267041, 56342.71828640831, 57142.0, 57241.427585723606, 79629.0, 83190.16809831825, 83404.0, 84050.57974710087, 85022.72465912893, 7961.920380796118, 8160.591970401575, 8218.301444377055, 8225.095239523527, 8230.300424463987, 11681.240719353214, 12256.325902075472, 12486.687832804253, 12505.56230531927, 12635.810462842881, 10784.89215107839, 10791.190660700322, 10841.241113122322, 10867.836982445428, 10955.452227388758, 10628.415437150721, 10705.143588513056, 10722.088622467178, 11028.448577570929, 11038.17213708959, 10793.946030270112, 11047.668569942593, 11253.549858005588, 11284.379359135326, 11336.036436902627, 8068.919310806817, 8078.959605201936, 8161.694128939341, 8188.918110818816, 8248.505089694638, 8130.715424960098, 10102.393856368348, 10911.781764364827, 11153.55385784592, 11169.944150279196], "value": 9670.303284174755, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1488.1220080152707, 1556.2607761313377, 1788.713805791073, 1907.1322548240544, 1950.4246247357028, 8224.383171262149, 8795.78010549735, 8801.735947921565, 9158.633654653806, 9172.541372931351, 8726.825463490726, 8752.343574231942, 8872.20150186483, 8886.688965886195, 8899.33255005874, 2393.3418309964727, 2434.1115492845074, 2439.5852705040097, 2441.5849305618, 2449.0448724997264, 2417.867017314047, 2422.1764600035985, 2444.2300675287315, 2458.397692565324, 2462.963055554168, 2056.7531896172404, 2211.900464479102, 2430.5017471418405, 2432.3067925641194, 2438.500107477963, 2417.830751847371, 2421.225207933462, 2423.2366804456715, 2441.1700021992606, 2459.2007597530874, 1988.8508361872773, 2207.5253820428684, 2273.999440246286, 2364.2552595932225, 2415.830891837572, 7790.5325680459355, 8384.161583841567, 8997.820043599157, 9039.954800226022, 9077.137671921157, 5380.865478363072, 5555.833325000256, 5586.608937374385, 5599.972000140014, 5631.493165615114, 3582.695470884978, 3587.856485740567, 3596.9100772480638, 3720.0513868963512, 3724.8696295629793, 909.5179554835947, 1007.2143727892255, 1050.2385770316537, 1056.5826498533113, 1061.1298735037271, 9981.750456238653, 11715.707107322214, 11833.230839995458, 12249.387530623433, 12257.877421225763, 4836.492168322346, 4929.75351232437, 5007.799688012438, 5008.924866127011, 5011.799528018912, 10314.329568578018, 10385.688429346981, 10387.584496620051, 10391.220658450646, 10391.84412233817, 5526.530244929226, 5534.972325138348, 5577.274954256002, 5603.271574695264, 5622.718864056781, 4047.4738284022887, 4461.732296062247, 4964.032013757354, 5056.115179843586, 5093.159628661304, 4108.5275193352545, 4141.819581419281, 4161.855489740346, 4169.3120635094865, 4188.036751547101, 1349.311850956, 1360.7890776929592, 1372.9038967272195, 1385.6189547874415, 1420.3679362683713, 675.8175292670951, 899.2266650680409, 917.2295271971576, 945.3193700535603, 956.1490273656451, 5645.802396916089, 5931.9703401483575, 5969.582129250997, 6123.908141377883, 6203.875922481525, 904.2314033071967, 991.3209451525728, 1000.6197644895038, 1005.5927349423495, 1010.3028910052041, 3843.442700808423, 3860.4016377460975, 3904.902377440587, 3965.3060714375447, 4000.959990400117, 10383.792324153626, 10851.131909447226, 10933.45332733376, 10958.671239862817, 10960.12319014477, 10833.970772776574, 10956.945215273872, 11002.50488728009, 11022.228444008842, 11088.0, 8335.49987000782, 8357.289630381363, 8426.325893928471, 8470.364722645827, 8472.237498625153, 5719.714014299185, 5929.6145750526075, 5932.614380065282, 6085.087236914498, 6172.660503672341, 9799.363041402286, 9801.362911410735, 10088.092071713581, 10113.0, 10113.393196408239, 10279.588816447558, 10307.278490505592, 10314.968503149625, 10384.169266458513, 10443.791124177626, 6945.895811562932, 7283.562986220843, 7484.625768711434, 7621.352185064332, 7714.0, 8422.578871056545, 8438.957805210935, 8450.957745211235, 8510.446820956618, 8512.233898949124, 7475.588842613708, 7586.393088553013, 7665.195154508696, 7715.996920400312, 7719.575423351769, 7424.888626670712, 7481.139668937924, 7507.549547027196, 7563.130240022468, 7614.466987310835, 6028.728707208098, 6894.724211031503, 7067.752628657972, 7069.081019467436, 7107.715691372287, 4216.7259128156575, 4745.857624271277, 4787.755183652206, 4798.2802579613335, 4912.92630610534, 4131.256373852735, 4161.063760653949, 4925.753712314442, 4981.626378021735, 5033.5721463676], "value": 4652.921481905553, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [302.2775566396312, 306.8941215280728, 310.30646505061196, 317.3874422364835, 318.4331889237157, 4812.494688057756, 5185.922211166836, 5320.547753440953, 5424.484673955978, 5430.53840423564, 4883.755812209384, 5434.91847622285, 5439.619226654126, 5459.044667183237, 5482.985647655187, 3828.5979972102946, 3980.1840622672426, 4165.687573431989, 4201.235481097931, 4215.66274698025, 3801.771893686374, 3902.2780785554764, 3935.9803200983956, 4205.179989901971, 4216.241076606209, 3963.135942456111, 4062.5937406259286, 4098.938515922264, 4107.917841643165, 4109.630133288004, 3214.083986063972, 3661.835217415223, 3980.7014473914282, 4110.671146308303, 4148.709590328648, 3530.452779819132, 3569.5181150544604, 3580.6240344763687, 3581.224550973017, 3588.4437912123662, 4183.3515805050265, 4341.978290108561, 4943.752812359367, 5054.545090941833, 5307.840764777025, 3778.659920607131, 4675.667434781071, 4704.388429504177, 4710.8351207707565, 4734.763261836929, 4797.424309082898, 5398.893226888478, 5775.509081728058, 5802.97098514509, 5844.53243740504, 3081.9691803081905, 3283.917902052444, 3730.552333719971, 3897.7271590988644, 3933.5476420211758, 4775.832845850413, 5024.924626130611, 6006.549508786815, 6044.24446944132, 6112.63324200545, 3932.3511620582854, 3956.4460975463458, 3973.324534829086, 3973.8609148680234, 3995.840166393312, 3727.0, 3817.255635151119, 4035.6569691575955, 4042.0, 4044.393340998815, 4543.227651299287, 4563.748993805306, 4652.395188625457, 4671.15919134559, 4715.434147902273, 5464.754086066137, 5832.387599302097, 5835.315754752861, 5846.0, 5882.294154682522, 1229.237872519044, 1425.565202613213, 1430.6623307207794, 1457.8979471436899, 1478.4012474947663, 796.3867821777314, 839.7228914458215, 858.994975878224, 956.6221342569646, 970.9708708738749, 4628.490866004804, 5808.651480911074, 5843.29880414353, 5861.501772349313, 5870.823875283749, 5141.920196758648, 5420.701861397582, 6435.485161187094, 6508.088867558546, 6515.674216289167, 4525.479569849444, 5480.232767412565, 5481.75332110056, 5817.156512305776, 5827.35899051104, 3145.9528107078413, 3406.284680217182, 3407.931841363159, 3414.436617958059, 3422.19578399076, 5735.942640573541, 6168.136460895478, 6218.5647004710145, 6548.8362790930605, 6779.220389655253, 6321.1782468278825, 6653.667316634149, 6656.96671516649, 6658.766943156967, 6684.465242780566, 5650.626736770566, 5705.3169315052155, 6517.641529715911, 6812.625305608239, 6903.620300883399, 4896.877578060577, 5695.971520142372, 5728.513076388554, 5741.799037033851, 6502.967485162544, 5383.380911195106, 5640.774369025193, 6384.872302554016, 6400.359964003563, 6453.354664533509, 5693.544516438592, 6094.299155597163, 6701.262861085179, 6763.7632682855865, 6779.491538134561, 4153.813078411418, 4178.540360560275, 4212.241796476664, 4218.557051509547, 4237.618614324726, 4946.985867897, 5106.62121227274, 5390.04400137842, 5949.970250148895, 6500.25247096571, 4892.339534162842, 5742.856428589152, 5750.645536681906, 5760.539156867357, 5801.477866991991, 5709.34342550612, 5756.568257380797, 5763.337216220189, 5773.566982476415, 5788.27646544182, 4379.781010949376, 4954.752262386938, 5608.663480191201, 5688.203651488712, 5793.768249269982, 5093.898122037613, 5484.8207635358585, 5843.707814609167, 5873.0, 5883.9403510175325, 4776.426828780569, 5020.849374518771, 5453.700046497481, 5652.78465129999, 5753.0], "value": 4249.721386958374, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [591.633604788752, 686.1491750229717, 726.4224941171768, 742.6546655805053, 757.0990521279675, 14553.78169327461, 14895.851041489583, 15064.322105505238, 15099.018563793346, 15149.091054536722, 13714.519991800289, 13714.862851371508, 13745.969052321067, 13752.831009364216, 13760.174389536609, 889.4929889962724, 893.6827426263663, 937.1846493550587, 937.5921474158729, 940.4827344960279, 918.3892711346946, 930.0234753508822, 931.1852129386787, 932.4871320773582, 933.6358820060182, 964.8215080210149, 979.8187335342984, 984.119213304096, 985.0789511806448, 987.2447577603103, 836.2139588786551, 974.7952929884733, 975.1857199238644, 975.8194733974167, 976.7753416714124, 709.6593635055151, 721.1850608812061, 726.1794172584998, 726.4079774983371, 727.4180655475651, 15290.388384464608, 15296.617584560363, 15322.080675159526, 15337.23313834315, 15342.309596068204, 12203.145779795417, 12389.380530973505, 12411.503539858395, 12411.62765117048, 12641.2415255085, 9743.610255589769, 9885.505724713807, 10315.381077135396, 10379.740506487322, 10386.221033422524, 3420.7263418926427, 3547.3614749345106, 3584.1577229351083, 3607.783532988029, 3672.797996110213, 81749.0, 82244.76632850512, 83004.58497707473, 83441.16558834395, 83904.58047709783, 9426.963034066248, 9542.3320367575, 9580.76048098803, 9594.472304023206, 9759.12167904892, 16812.57968550796, 20866.791332086486, 20909.37271881846, 21178.576428471653, 21362.465938351666, 7459.030326057577, 7464.888026679604, 7517.17311095779, 7597.962010190025, 7796.649150788228, 4117.608827161415, 4120.814563344657, 4173.6661067114555, 4184.184084103632, 4301.892452688708, 3116.6415862175686, 3474.4961980512685, 3521.3133438979153, 3590.2101537661674, 3601.1717305019993, 595.7259660556106, 607.6232735703892, 609.6068036116679, 613.6624856329001, 613.9416755408229, 573.9754538149383, 574.5432381256911, 584.1588113117098, 592.0083859535262, 612.0818771842237, 8154.6738130475305, 8185.222403871623, 8337.916620833836, 8357.791055223546, 8493.830123397622, 520.7812718658173, 576.1760682224376, 583.553581510145, 583.8598736303342, 584.3893131677407, 3007.6691563927952, 3189.091109033914, 3795.3737633290198, 3914.9554282075956, 4128.8348466061625, 90585.09414905899, 96757.0, 97941.04117917601, 102966.45550316751, 103106.48446757719, 67285.0, 78037.4392512158, 78740.0, 79316.60341698254, 80947.59526202331, 71197.2880271201, 73240.0, 73313.0, 73378.63310683519, 74160.0, 7699.961500192463, 7827.295543401121, 7905.169957154416, 7940.722074727357, 8066.7579972600915, 46479.0, 46833.063338733715, 47123.764381177876, 47562.76218618885, 47582.28626570674, 64909.67545162244, 66948.66525667341, 68256.6587167061, 68305.0, 69052.65473672599, 10009.449480278363, 10156.746081348025, 10161.136303414292, 10202.795944081225, 10534.104601108686, 11126.221164518729, 11169.0, 11222.7755444889, 11329.546818127183, 11444.94277528607, 10428.687139385831, 10512.632057877938, 10558.04977552024, 10684.0, 10791.730206744895, 10369.274150809371, 10515.737106572396, 10537.73655658584, 10632.25574209798, 11018.834717479405, 8452.957735211285, 9974.551145198338, 10281.434521101412, 10601.681949541526, 10777.191710621892, 8905.287576993955, 8923.643054277756, 9086.727398178065, 9232.215261702562, 9279.39683920543, 8936.0, 8937.329700272376, 9004.144606262395, 9054.230390416626, 9128.817423651622], "value": 6793.355538368025, "unit": "score"}], "name": "dromaeo_css", "value": 7171.102127105427, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 165.0, 167.0, 121.0, 117.0, 132.0, 117.0, 114.0, 117.0, 120.0], "value": 132.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 123.0, 183.0, 159.0, 121.0, 140.0, 143.0, 122.0, 184.0, 156.0], "value": 148.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [203.0, 199.0, 200.0, 197.0, 198.0, 246.0, 225.0, 196.0, 201.0, 201.0], "value": 206.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 78.0, 75.0, 149.0, 58.0, 58.0, 56.0, 63.0, 132.0, 56.0], "value": 79.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 88.0, 129.0, 77.0, 79.0, 87.0, 123.0, 81.0, 80.0, 121.0], "value": 94.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 89.0, 91.0, 89.0, 91.0, 88.0, 92.0, 89.0, 92.0, 90.0], "value": 94.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 97.0, 97.0, 95.0, 97.0, 128.0, 147.0, 101.0, 126.0, 102.0], "value": 108.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 91.0, 125.0, 94.0, 88.0, 76.0, 115.0, 146.0, 147.0], "value": 106.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 66.0, 67.0, 66.0, 68.0, 65.0, 68.0, 81.0, 64.0, 61.0], "value": 66.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 70.0, 73.0, 74.0, 71.0, 73.0, 66.0, 69.0, 68.0], "value": 69.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 85.0, 90.0, 68.0, 99.0, 73.0, 105.0, 105.0, 113.0, 85.0], "value": 90.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 139.0, 135.0, 136.0, 138.0, 136.0, 161.0, 156.0, 194.0, 149.0], "value": 147.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 184.0, 170.0, 182.0, 234.0, 176.0, 171.0, 170.0, 171.0, 228.0], "value": 185.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 68.0, 71.0, 70.0, 68.0, 68.0, 68.0, 66.0, 69.0, 70.0], "value": 68.9, "unit": "score"}], "name": "kraken", "value": 1602.2000000000007, "alertThreshold": 2.0}]} 13:31:42 INFO - Return code: 0 13:31:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 13:31:42 ERROR - # TBPL SUCCESS # 13:31:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:31:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:31:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:31:42 INFO - 2016-04-26 13:31:42.599 screenresolution[1690:10805] starting screenresolution argv=screenresolution get 13:31:42 INFO - 2016-04-26 13:31:42.601 screenresolution[1690:10805] Display 0: 1600x1200x32@60 13:31:42 INFO - 2016-04-26 13:31:42.607 screenresolution[1691:10808] starting screenresolution argv=screenresolution list 13:31:42 INFO - Available Modes on Display 0 13:31:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:31:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:31:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:31:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:31:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:31:42 INFO - Intel Iris: 13:31:42 INFO - Chipset Model: Intel Iris 13:31:42 INFO - Type: GPU 13:31:42 INFO - Bus: Built-In 13:31:42 INFO - VRAM (Dynamic, Max): 1536 MB 13:31:42 INFO - Vendor: Intel (0x8086) 13:31:42 INFO - Device ID: 0x0a2e 13:31:42 INFO - Revision ID: 0x0009 13:31:42 INFO - Displays: 13:31:42 INFO - Display: 13:31:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:31:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:31:42 INFO - Display Serial Number: 1600x1200 60 13:31:42 INFO - Main Display: Yes 13:31:42 INFO - Mirror: Off 13:31:42 INFO - Online: Yes 13:31:42 INFO - Rotation: Supported 13:31:42 INFO - Return code: 0 13:31:42 INFO - Running post-action listener: _resource_record_post_action 13:31:42 INFO - Running post-run listener: _resource_record_post_run 13:31:43 INFO - Total resource usage - Wall time: 1205s; CPU: 54.0%; Read bytes: 32415232; Write bytes: 654579200; Read time: 325; Write time: 1938 13:31:43 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 354583040; Write bytes: 356521984; Read time: 27810; Write time: 786 13:31:43 INFO - run-tests - Wall time: 1173s; CPU: 54.0%; Read bytes: 30629376; Write bytes: 297209344; Read time: 306; Write time: 1150 13:31:43 INFO - Running post-run listener: _upload_blobber_files 13:31:43 INFO - Blob upload gear active. 13:31:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:31:43 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:31:43 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:31:43 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:31:44 INFO - (blobuploader) - INFO - Open directory for files ... 13:31:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 13:31:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:31:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:31:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 13:31:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:31:45 INFO - (blobuploader) - INFO - Done attempting. 13:31:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 13:31:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:31:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:31:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 13:31:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:31:45 INFO - (blobuploader) - INFO - Done attempting. 13:31:45 INFO - (blobuploader) - INFO - Iteration through files over. 13:31:45 INFO - Return code: 0 13:31:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:31:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:31:45 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/582646b8cd93087983cef8f1ef053f8706a3a3d062fe99e2719afbd6b352c10592b563144d02ca786c892ae77cf447ee2bfc7c44a2eaaf88590d5e9de7ec55dd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/76f25eeffa1fbdaab14a21b850f8ca637ac4a3b86fee131b2eb9a7ae28e44ba563dc3b60af63bed3786b5106a0910d46aab9b2dfc3b2a71f8a1579ceb42b9dfe"} 13:31:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:31:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:31:45 INFO - Contents: 13:31:45 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/582646b8cd93087983cef8f1ef053f8706a3a3d062fe99e2719afbd6b352c10592b563144d02ca786c892ae77cf447ee2bfc7c44a2eaaf88590d5e9de7ec55dd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/76f25eeffa1fbdaab14a21b850f8ca637ac4a3b86fee131b2eb9a7ae28e44ba563dc3b60af63bed3786b5106a0910d46aab9b2dfc3b2a71f8a1579ceb42b9dfe"} 13:31:45 INFO - Running post-run listener: copy_logs_to_upload_dir 13:31:45 INFO - Copying logs to upload dir... 13:31:45 INFO - mkdir: /builds/slave/test/build/upload/logs 13:31:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.882911 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-04-26 13:31:46.068386) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-26 13:31:46.075504) ========= 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.h4O8yhjdm5/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.61t1aKnGNM/Listeners TMPDIR=/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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/582646b8cd93087983cef8f1ef053f8706a3a3d062fe99e2719afbd6b352c10592b563144d02ca786c892ae77cf447ee2bfc7c44a2eaaf88590d5e9de7ec55dd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/76f25eeffa1fbdaab14a21b850f8ca637ac4a3b86fee131b2eb9a7ae28e44ba563dc3b60af63bed3786b5106a0910d46aab9b2dfc3b2a71f8a1579ceb42b9dfe"} build_url:https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010853 build_url: 'https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/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/582646b8cd93087983cef8f1ef053f8706a3a3d062fe99e2719afbd6b352c10592b563144d02ca786c892ae77cf447ee2bfc7c44a2eaaf88590d5e9de7ec55dd", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/76f25eeffa1fbdaab14a21b850f8ca637ac4a3b86fee131b2eb9a7ae28e44ba563dc3b60af63bed3786b5106a0910d46aab9b2dfc3b2a71f8a1579ceb42b9dfe"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-26 13:31:46.111025) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 13:31:46.111335) ========= 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.h4O8yhjdm5/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.61t1aKnGNM/Listeners TMPDIR=/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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.005144 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 13:31:46.166336) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-26 13:31:46.166630) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-26 13:31:46.166979) ========= ========= Total master_lag: 1.76 =========