builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0200 starttime: 1462025877.05 results: success (0) buildid: 20160430051339 builduid: 142b00fc01194d06afe3a09b60593ffa revision: 3f169c95e542f059b6b07d11e7f73533bdbf03d6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-30 07:17:57.051783) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-30 07:17:57.052566) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 1 mins, 25 secs) (at 2016-04-30 07:17:57.052838) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 1 mins, 25 secs) (at 2016-04-30 07:19:22.701598) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 19 secs) (at 2016-04-30 07:19:22.701911) ========= 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.qWvtO6yxcT/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.vpEHavVyBJ/Listeners TMPDIR=/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/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-30 07:19:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 159M=0s 2016-04-30 07:19:26 (159 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.487812 ========= master_lag: 18.84 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 19 secs) (at 2016-04-30 07:19:42.032863) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 4 secs) (at 2016-04-30 07:19:42.033183) ========= 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.qWvtO6yxcT/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.vpEHavVyBJ/Listeners TMPDIR=/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/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.029867 ========= master_lag: 4.85 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 4 secs) (at 2016-04-30 07:19:46.912933) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 8 secs) (at 2016-04-30 07:19:46.913293) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3f169c95e542f059b6b07d11e7f73533bdbf03d6 --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 3f169c95e542f059b6b07d11e7f73533bdbf03d6 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qWvtO6yxcT/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.vpEHavVyBJ/Listeners TMPDIR=/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/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-30 07:19:49,873 truncating revision to first 12 chars 2016-04-30 07:19:49,873 Setting DEBUG logging. 2016-04-30 07:19:49,873 attempt 1/10 2016-04-30 07:19:49,873 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/3f169c95e542?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-30 07:19:51,843 unpacking tar archive at: mozilla-aurora-3f169c95e542/testing/mozharness/ program finished with exit code 0 elapsedTime=2.222455 ========= master_lag: 6.53 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 8 secs) (at 2016-04-30 07:19:55.665695) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-30 07:19:55.666021) ========= script_repo_revision: 3f169c95e542f059b6b07d11e7f73533bdbf03d6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-30 07:19:55.669448) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 33 secs) (at 2016-04-30 07:19:55.669714) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 33 secs) (at 2016-04-30 07:20:29.251226) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2016-04-30 07:20:29.251643) ========= /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.qWvtO6yxcT/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.vpEHavVyBJ/Listeners TMPDIR=/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/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 07:20:30 INFO - MultiFileLogger online at 20160430 07:20:30 in /builds/slave/test 07:20:30 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 07:20:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:20:30 INFO - {'append_to_log': False, 07:20:30 INFO - 'base_work_dir': '/builds/slave/test', 07:20:30 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 07:20:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:20:30 INFO - 'branch': 'Mozilla-Aurora', 07:20:30 INFO - 'buildbot_json_path': 'buildprops.json', 07:20:30 INFO - 'config_files': ('talos/mac_config.py',), 07:20:30 INFO - 'default_actions': ('clobber', 07:20:30 INFO - 'read-buildbot-config', 07:20:30 INFO - 'download-and-extract', 07:20:30 INFO - 'populate-webroot', 07:20:30 INFO - 'create-virtualenv', 07:20:30 INFO - 'install', 07:20:30 INFO - 'run-tests'), 07:20:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:20:30 INFO - 'download_minidump_stackwalk': True, 07:20:30 INFO - 'download_symbols': 'ondemand', 07:20:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:20:30 INFO - 'tooltool.py': '/tools/tooltool.py', 07:20:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:20:30 INFO - '/tools/misc-python/virtualenv.py')}, 07:20:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:20:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:20:30 INFO - 'installer_path': 'installer.exe', 07:20:30 INFO - 'log_level': 'info', 07:20:30 INFO - 'log_name': 'talos', 07:20:30 INFO - 'log_to_console': True, 07:20:30 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:20:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:20:30 INFO - 'opt_config_files': (), 07:20:30 INFO - 'pip_index': False, 07:20:30 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:20:30 INFO - 'cmd': ('bash', 07:20:30 INFO - '-c', 07:20:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:20:30 INFO - 'enabled': True, 07:20:30 INFO - 'halt_on_failure': False, 07:20:30 INFO - 'name': 'check_screen_resolution'},), 07:20:30 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:20:30 INFO - 'cmd': ('bash', 07:20:30 INFO - '-c', 07:20:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:20:30 INFO - 'enabled': True, 07:20:30 INFO - 'halt_on_failure': False, 07:20:30 INFO - 'name': 'check_screen_resolution'},), 07:20:30 INFO - 'run_cmd_checks_enabled': True, 07:20:30 INFO - 'sps_profile': False, 07:20:30 INFO - 'sps_profile_interval': 0, 07:20:30 INFO - 'suite': 'dromaeojs-e10s', 07:20:30 INFO - 'system_bits': '32', 07:20:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:20:30 INFO - 'title': 't-yosemite-r7-0200', 07:20:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:20:30 INFO - 'use_talos_json': True, 07:20:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:20:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:20:30 INFO - 'work_dir': 'build'} 07:20:30 INFO - ##### 07:20:30 INFO - ##### Running clobber step. 07:20:30 INFO - ##### 07:20:30 INFO - Running pre-action listener: _resource_record_pre_action 07:20:30 INFO - Running main action method: clobber 07:20:30 INFO - rmtree: /builds/slave/test/build 07:20:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:20:32 INFO - Running post-action listener: _resource_record_post_action 07:20:32 INFO - ##### 07:20:32 INFO - ##### Running read-buildbot-config step. 07:20:32 INFO - ##### 07:20:32 INFO - Running pre-action listener: _resource_record_pre_action 07:20:32 INFO - Running main action method: read_buildbot_config 07:20:32 INFO - Using buildbot properties: 07:20:32 INFO - { 07:20:32 INFO - "project": "", 07:20:32 INFO - "product": "firefox", 07:20:32 INFO - "script_repo_revision": "production", 07:20:32 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 07:20:32 INFO - "repository": "", 07:20:32 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 07:20:32 INFO - "buildid": "20160430051339", 07:20:32 INFO - "pgo_build": "False", 07:20:32 INFO - "basedir": "/builds/slave/test", 07:20:32 INFO - "buildnumber": 278, 07:20:32 INFO - "slavename": "t-yosemite-r7-0200", 07:20:32 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 07:20:32 INFO - "platform": "yosemite_r7", 07:20:32 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 07:20:32 INFO - "branch": "mozilla-aurora", 07:20:32 INFO - "repo_path": "releases/mozilla-aurora", 07:20:32 INFO - "revision": "3f169c95e542f059b6b07d11e7f73533bdbf03d6", 07:20:32 INFO - "stage_platform": "macosx64", 07:20:32 INFO - "builduid": "142b00fc01194d06afe3a09b60593ffa", 07:20:32 INFO - "slavebuilddir": "test" 07:20:32 INFO - } 07:20:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 07:20:32 INFO - Running post-action listener: _resource_record_post_action 07:20:32 INFO - ##### 07:20:32 INFO - ##### Running download-and-extract step. 07:20:32 INFO - ##### 07:20:32 INFO - Running pre-action listener: _resource_record_pre_action 07:20:32 INFO - Running main action method: download_and_extract 07:20:32 INFO - mkdir: /builds/slave/test/build/tests 07:20: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')]} 07:20:32 INFO - https://queue.taskcluster.net/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 07:20:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 07:20:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 07:20:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XbLHIlVfRManBcgwvhJmMw/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 07:20:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XbLHIlVfRManBcgwvhJmMw/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 07:20:47 INFO - Downloaded 1199 bytes. 07:20:47 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 07:20:47 INFO - Using the following test package requirements: 07:20:47 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 07:20:47 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 07:20:47 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 07:20:47 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 07:20:47 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 07:20:47 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 07:20:47 INFO - u'jsshell-mac.zip'], 07:20:47 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 07:20:47 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 07:20:47 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 07:20:47 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 07:20:47 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 07:20:47 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 07:20:47 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 07:20:47 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 07:20:47 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 07:20:47 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 07:20:47 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 07:20:47 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 07:20:47 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')]} 07:20:47 INFO - https://queue.taskcluster.net/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:20:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 07:20:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 07:20:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XbLHIlVfRManBcgwvhJmMw/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 07:20:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XbLHIlVfRManBcgwvhJmMw/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 07:20:51 INFO - Downloaded 18110494 bytes. 07:20:51 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'] 07:20:51 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 07:20:54 INFO - Return code: 0 07:20:54 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 07:20:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:20:54 INFO - https://queue.taskcluster.net/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:20:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 07:20:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 07:20:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XbLHIlVfRManBcgwvhJmMw/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 07:20:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XbLHIlVfRManBcgwvhJmMw/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 07:20:55 INFO - Downloaded 18110494 bytes. 07:20:55 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'] 07:20:55 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 07:20:59 INFO - Return code: 0 07:20:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:20:59 INFO - https://queue.taskcluster.net/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 07:20:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 07:20:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 07:20:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XbLHIlVfRManBcgwvhJmMw/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 07:20:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XbLHIlVfRManBcgwvhJmMw/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 07:21:00 INFO - Downloaded 10840145 bytes. 07:21:00 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'] 07:21:00 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 07:21:01 INFO - Return code: 0 07:21:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:21:01 INFO - https://queue.taskcluster.net/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 07:21:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 07:21:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 07:21:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 07:21:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XbLHIlVfRManBcgwvhJmMw/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 07:21:06 INFO - Downloaded 114073247 bytes. 07:21:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 07:21:06 INFO - mkdir: /builds/slave/test/properties 07:21:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:21:06 INFO - Writing to file /builds/slave/test/properties/build_url 07:21:06 INFO - Contents: 07:21:06 INFO - build_url:https://queue.taskcluster.net/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 07:21:07 INFO - Running post-action listener: _resource_record_post_action 07:21:07 INFO - Running post-action listener: set_extra_try_arguments 07:21:07 INFO - ##### 07:21:07 INFO - ##### Running populate-webroot step. 07:21:07 INFO - ##### 07:21:07 INFO - Running pre-action listener: _resource_record_pre_action 07:21:07 INFO - Running main action method: populate_webroot 07:21:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:21:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:21:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:21:07 INFO - '--tppagecycles', 07:21:07 INFO - '7'], 07:21:07 INFO - 'tests': ['tsvgm']}}, 07:21:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:21:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:21:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:21:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:21:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:21:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:21:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:21:07 INFO - 'talos_options': ['--disable-e10s'], 07:21:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:21:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:21:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:21:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:21:07 INFO - 'talos_options': ['--disable-e10s'], 07:21:07 INFO - 'tests': ['damp', 'tps']}, 07:21:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:21:07 INFO - 'tests': ['damp', 'tps']}, 07:21:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:21:07 INFO - 'tests': ['dromaeo_dom']}, 07:21:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:21:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:21:07 INFO - 'tests': ['a11yr', 07:21:07 INFO - 'ts_paint', 07:21:07 INFO - 'tpaint', 07:21:07 INFO - 'sessionrestore', 07:21:07 INFO - 'sessionrestore_no_auto_restore', 07:21:07 INFO - 'tabpaint']}, 07:21:07 INFO - 'other-e10s': {'tests': ['a11yr', 07:21:07 INFO - 'ts_paint', 07:21:07 INFO - 'tpaint', 07:21:07 INFO - 'sessionrestore', 07:21:07 INFO - 'sessionrestore_no_auto_restore', 07:21:07 INFO - 'tabpaint']}, 07:21:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:21:07 INFO - 'ts_paint', 07:21:07 INFO - 'tpaint', 07:21:07 INFO - 'sessionrestore', 07:21:07 INFO - 'sessionrestore_no_auto_restore', 07:21:07 INFO - 'tabpaint']}, 07:21:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:21:07 INFO - 'ts_paint', 07:21:07 INFO - 'tpaint', 07:21:07 INFO - 'sessionrestore', 07:21:07 INFO - 'sessionrestore_no_auto_restore', 07:21:07 INFO - 'tabpaint']}, 07:21:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:21:07 INFO - 'tests': ['a11yr', 07:21:07 INFO - 'ts_paint', 07:21:07 INFO - 'tpaint', 07:21:07 INFO - 'sessionrestore', 07:21:07 INFO - 'sessionrestore_no_auto_restore', 07:21:07 INFO - 'tabpaint']}, 07:21:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:21:07 INFO - 'tests': ['a11yr', 07:21:07 INFO - 'ts_paint', 07:21:07 INFO - 'tpaint', 07:21:07 INFO - 'sessionrestore', 07:21:07 INFO - 'sessionrestore_no_auto_restore', 07:21:07 INFO - 'tabpaint']}, 07:21:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:21:07 INFO - 'tests': ['tsvgx', 07:21:07 INFO - 'tsvgr_opacity', 07:21:07 INFO - 'tart', 07:21:07 INFO - 'tscrollx', 07:21:07 INFO - 'cart']}, 07:21:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:21:07 INFO - 'tsvgr_opacity', 07:21:07 INFO - 'tart', 07:21:07 INFO - 'tscrollx', 07:21:07 INFO - 'cart']}, 07:21:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:21:07 INFO - 'talos_options': ['--disable-e10s'], 07:21:07 INFO - 'tests': ['tp5o']}, 07:21:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:21:07 INFO - 'tests': ['tp5o']}, 07:21:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:21:07 INFO - 'talos_options': ['--disable-e10s', 07:21:07 INFO - '--xperf_path', 07:21:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:21:07 INFO - 'tests': ['tp5n']}, 07:21:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:21:07 INFO - 'talos_options': ['--xperf_path', 07:21:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:21:07 INFO - 'tests': ['tp5n']}}, 07:21:07 INFO - 'talos.zip': {'path': '', 07:21:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:21:07 INFO - Running post-action listener: _resource_record_post_action 07:21:07 INFO - ##### 07:21:07 INFO - ##### Running create-virtualenv step. 07:21:07 INFO - ##### 07:21:07 INFO - Running pre-action listener: _resource_record_pre_action 07:21:07 INFO - Running main action method: create_virtualenv 07:21:07 INFO - Creating virtualenv /builds/slave/test/build/venv 07:21:07 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 07:21:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:21:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:21:07 INFO - Using real prefix '/tools/python27' 07:21:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:21:08 INFO - Installing distribute.............................................................................................................................................................................................done. 07:21:10 INFO - Installing pip.................done. 07:21:10 INFO - Return code: 0 07:21:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:21:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:21:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:21:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:21:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:21:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:10 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 0x107cd6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ce6420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c7a4aabd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b75e00>, '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 0x1079dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b70b20>, '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.qWvtO6yxcT/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.vpEHavVyBJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/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 07:21:10 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 07:21:10 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 07:21:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qWvtO6yxcT/Render', 07:21:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:21:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:21:10 INFO - 'HOME': '/Users/cltbld', 07:21:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:21:10 INFO - 'LOGNAME': 'cltbld', 07:21:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:21:10 INFO - 'MOZ_NO_REMOTE': '1', 07:21:10 INFO - 'NO_EM_RESTART': '1', 07:21:10 INFO - 'PAGER': '/bin/cat', 07:21:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:21:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:21:10 INFO - 'PWD': '/builds/slave/test', 07:21:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:21:10 INFO - 'SHELL': '/bin/bash', 07:21:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vpEHavVyBJ/Listeners', 07:21:10 INFO - 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/', 07:21:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:21:10 INFO - 'USER': 'cltbld', 07:21:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:21:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:21:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:21:10 INFO - 'XPC_FLAGS': '0x0', 07:21:10 INFO - 'XPC_SERVICE_NAME': '0', 07:21:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:21:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:21:11 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 07:21:11 INFO - Cleaning up... 07:21:11 INFO - Return code: 0 07:21:11 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 07:21: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')]} 07:21:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:21:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21: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')]} 07:21:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:21:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21: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', '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 0x107cd6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ce6420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c7a4aabd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b75e00>, '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 0x1079dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b70b20>, '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.qWvtO6yxcT/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.vpEHavVyBJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/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 07:21: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', 'psutil>=0.7.1'] in /builds/slave/test/build 07:21: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 psutil>=0.7.1 07:21:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qWvtO6yxcT/Render', 07:21:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:21:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:21:11 INFO - 'HOME': '/Users/cltbld', 07:21:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:21:11 INFO - 'LOGNAME': 'cltbld', 07:21:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:21:11 INFO - 'MOZ_NO_REMOTE': '1', 07:21:11 INFO - 'NO_EM_RESTART': '1', 07:21:11 INFO - 'PAGER': '/bin/cat', 07:21:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:21:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:21:11 INFO - 'PWD': '/builds/slave/test', 07:21:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:21:11 INFO - 'SHELL': '/bin/bash', 07:21:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vpEHavVyBJ/Listeners', 07:21:11 INFO - 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/', 07:21:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:21:11 INFO - 'USER': 'cltbld', 07:21:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:21:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:21:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:21:11 INFO - 'XPC_FLAGS': '0x0', 07:21:11 INFO - 'XPC_SERVICE_NAME': '0', 07:21:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:21:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:21:11 INFO - Downloading/unpacking psutil>=0.7.1 07:21: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 07:21: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 07:21: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 07:21: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 07:21: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 07:21: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 07:21:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:21:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:21:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:21:14 INFO - Installing collected packages: psutil 07:21:14 INFO - Running setup.py install for psutil 07:21:14 INFO - building 'psutil._psutil_osx' extension 07:21:14 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 07:21:15 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 07:21:15 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 07:21:15 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 07:21:15 INFO - building 'psutil._psutil_posix' extension 07:21:15 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 07:21:15 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:21:15 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:21:15 INFO - ^ 07:21:15 INFO - 1 warning generated. 07:21:15 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 07:21:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:21:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:21:15 INFO - Successfully installed psutil 07:21:15 INFO - Cleaning up... 07:21:15 INFO - Return code: 0 07:21:15 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 07:21:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:21:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:21:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:21:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:21:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:15 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 0x107cd6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ce6420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c7a4aabd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b75e00>, '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 0x1079dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b70b20>, '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.qWvtO6yxcT/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.vpEHavVyBJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/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 07:21:15 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 07:21:15 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 07:21:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qWvtO6yxcT/Render', 07:21:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:21:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:21:15 INFO - 'HOME': '/Users/cltbld', 07:21:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:21:15 INFO - 'LOGNAME': 'cltbld', 07:21:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:21:15 INFO - 'MOZ_NO_REMOTE': '1', 07:21:15 INFO - 'NO_EM_RESTART': '1', 07:21:15 INFO - 'PAGER': '/bin/cat', 07:21:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:21:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:21:15 INFO - 'PWD': '/builds/slave/test', 07:21:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:21:15 INFO - 'SHELL': '/bin/bash', 07:21:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vpEHavVyBJ/Listeners', 07:21:15 INFO - 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/', 07:21:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:21:15 INFO - 'USER': 'cltbld', 07:21:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:21:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:21:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:21:15 INFO - 'XPC_FLAGS': '0x0', 07:21:15 INFO - 'XPC_SERVICE_NAME': '0', 07:21:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:21:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:21:15 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 07:21:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:21:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:21:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:21:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:21:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:21:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:21:18 INFO - Downloading mozsystemmonitor-0.0.tar.gz 07:21:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:21:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 07:21:18 INFO - Installing collected packages: mozsystemmonitor 07:21:18 INFO - Running setup.py install for mozsystemmonitor 07:21:18 INFO - Successfully installed mozsystemmonitor 07:21:18 INFO - Cleaning up... 07:21:18 INFO - Return code: 0 07:21:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:21:18 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')]} 07:21:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:21:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:18 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')]} 07:21:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:21:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:18 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 0x107cd6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ce6420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c7a4aabd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b75e00>, '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 0x1079dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b70b20>, '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.qWvtO6yxcT/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.vpEHavVyBJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/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 07:21:18 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 07:21:18 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 07:21:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qWvtO6yxcT/Render', 07:21:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:21:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:21:18 INFO - 'HOME': '/Users/cltbld', 07:21:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:21:18 INFO - 'LOGNAME': 'cltbld', 07:21:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:21:18 INFO - 'MOZ_NO_REMOTE': '1', 07:21:18 INFO - 'NO_EM_RESTART': '1', 07:21:18 INFO - 'PAGER': '/bin/cat', 07:21:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:21:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:21:18 INFO - 'PWD': '/builds/slave/test', 07:21:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:21:18 INFO - 'SHELL': '/bin/bash', 07:21:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vpEHavVyBJ/Listeners', 07:21:18 INFO - 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/', 07:21:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:21:18 INFO - 'USER': 'cltbld', 07:21:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:21:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:21:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:21:18 INFO - 'XPC_FLAGS': '0x0', 07:21:18 INFO - 'XPC_SERVICE_NAME': '0', 07:21:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:21:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:21:18 INFO - Downloading/unpacking blobuploader==1.2.4 07:21:18 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 07:21:18 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 07:21:18 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 07:21:18 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 07:21:18 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 07:21:18 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 07:21:21 INFO - Downloading blobuploader-1.2.4.tar.gz 07:21:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:21:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:21:21 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 07:21:21 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 07:21:21 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 07:21:21 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 07:21:21 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 07:21:21 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 07:21:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:21:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:21:21 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 07:21:21 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 07:21:21 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 07:21:21 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 07:21:21 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 07:21:21 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 07:21:22 INFO - Downloading docopt-0.6.1.tar.gz 07:21:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:21:22 INFO - Installing collected packages: blobuploader, requests, docopt 07:21:22 INFO - Running setup.py install for blobuploader 07:21:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:21:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:21:22 INFO - Running setup.py install for requests 07:21:23 INFO - Running setup.py install for docopt 07:21:23 INFO - Successfully installed blobuploader requests docopt 07:21:23 INFO - Cleaning up... 07:21:23 INFO - Return code: 0 07:21:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:21: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')]} 07:21:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:21:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21: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')]} 07:21:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:21:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:23 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 0x107cd6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ce6420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c7a4aabd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b75e00>, '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 0x1079dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b70b20>, '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.qWvtO6yxcT/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.vpEHavVyBJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/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 07:21:23 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 07:21:23 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 07:21:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qWvtO6yxcT/Render', 07:21:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:21:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:21:23 INFO - 'HOME': '/Users/cltbld', 07:21:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:21:23 INFO - 'LOGNAME': 'cltbld', 07:21:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:21:23 INFO - 'MOZ_NO_REMOTE': '1', 07:21:23 INFO - 'NO_EM_RESTART': '1', 07:21:23 INFO - 'PAGER': '/bin/cat', 07:21:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:21:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:21:23 INFO - 'PWD': '/builds/slave/test', 07:21:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:21:23 INFO - 'SHELL': '/bin/bash', 07:21:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vpEHavVyBJ/Listeners', 07:21:23 INFO - 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/', 07:21:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:21:23 INFO - 'USER': 'cltbld', 07:21:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:21:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:21:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:21:23 INFO - 'XPC_FLAGS': '0x0', 07:21:23 INFO - 'XPC_SERVICE_NAME': '0', 07:21:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:21:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:21:23 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-EG5nJ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:21:23 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-v8Z8vz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:21:23 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-0soFV_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:21:23 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-JPA7pX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:21:23 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-G5IPTo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:21:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:21:24 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-wxQp1H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:21:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:21:24 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-tjj9R3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:21:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:21:24 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-6my5EN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:21:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:21:24 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-NfITPO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:21:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:21:24 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-RBmqY5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:21:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:21:24 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-YvI0Lf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:21:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:21:24 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-TN5V3T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:21:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:21:24 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-iXWoQq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:21:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:21:24 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-JnNawj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:21:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:21:24 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-oNYtGr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:21:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:21:25 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-rFw5k9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:21:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:21:25 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-DWCKrd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:21:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:21:25 INFO - Running setup.py install for manifestparser 07:21:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:21:25 INFO - Running setup.py install for mozcrash 07:21:25 INFO - Running setup.py install for mozdebug 07:21:25 INFO - Running setup.py install for mozdevice 07:21:25 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:21:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:21:25 INFO - Running setup.py install for mozfile 07:21:25 INFO - Running setup.py install for mozhttpd 07:21:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:21:26 INFO - Running setup.py install for mozinfo 07:21:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:21:26 INFO - Running setup.py install for mozInstall 07:21:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:21:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:21:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:21:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:21:26 INFO - Running setup.py install for mozleak 07:21:26 INFO - Running setup.py install for mozlog 07:21:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:21:26 INFO - Running setup.py install for moznetwork 07:21:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:21:26 INFO - Running setup.py install for mozprocess 07:21:27 INFO - Running setup.py install for mozprofile 07:21:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:21:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:21:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:21:27 INFO - Running setup.py install for mozrunner 07:21:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:21:27 INFO - Running setup.py install for mozscreenshot 07:21:27 INFO - Running setup.py install for moztest 07:21:27 INFO - Running setup.py install for mozversion 07:21:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:21:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:21:27 INFO - Cleaning up... 07:21:27 INFO - Return code: 0 07:21:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:21:27 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')]} 07:21:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:21:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:27 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')]} 07:21:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:21:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:27 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 0x107cd6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ce6420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c7a4aabd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b75e00>, '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 0x1079dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b70b20>, '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.qWvtO6yxcT/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.vpEHavVyBJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/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 07:21:27 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 07:21:27 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 07:21:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qWvtO6yxcT/Render', 07:21:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:21:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:21:27 INFO - 'HOME': '/Users/cltbld', 07:21:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:21:27 INFO - 'LOGNAME': 'cltbld', 07:21:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:21:27 INFO - 'MOZ_NO_REMOTE': '1', 07:21:27 INFO - 'NO_EM_RESTART': '1', 07:21:27 INFO - 'PAGER': '/bin/cat', 07:21:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:21:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:21:27 INFO - 'PWD': '/builds/slave/test', 07:21:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:21:27 INFO - 'SHELL': '/bin/bash', 07:21:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vpEHavVyBJ/Listeners', 07:21:27 INFO - 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/', 07:21:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:21:27 INFO - 'USER': 'cltbld', 07:21:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:21:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:21:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:21:27 INFO - 'XPC_FLAGS': '0x0', 07:21:27 INFO - 'XPC_SERVICE_NAME': '0', 07:21:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:21:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:21:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:21:28 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-sQaBGS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:21:28 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)) 07:21:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:21:28 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-bFOXZe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:21:28 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)) 07:21:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:21:28 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-RMv0ik-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:21:28 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)) 07:21:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:21:28 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-WZfpCn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:21:28 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)) 07:21:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:21:28 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-MhpNaq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:21:28 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)) 07:21:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:21:28 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-ikzcdG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:21:28 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)) 07:21:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:21:28 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-oUHA5r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:21:28 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)) 07:21:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:21:28 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-dJtaU3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:21:28 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)) 07:21:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:21:28 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-7k0tjB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:21:29 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)) 07:21:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:21:29 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-hnkIBr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:21:29 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)) 07:21:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:21:29 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-UOCugC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:21:29 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)) 07:21:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:21:29 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-H8winc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:21:29 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)) 07:21:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:21:29 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-XN0BoE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:21:29 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)) 07:21:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:21:29 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-9ScuRU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:21:29 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)) 07:21:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:21:29 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-st0kU8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:21:29 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)) 07:21:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:21:29 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-zPPxXa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:21:29 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)) 07:21:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:21:29 INFO - Running setup.py (path:/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/pip-wqHEWy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:21:29 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)) 07:21:29 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)) 07:21:29 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)) 07:21:29 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)) 07:21:29 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)) 07:21:29 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)) 07:21:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:21: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 07:21: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 07:21: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 07:21: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 07:21: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 07:21: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 07:21:32 INFO - Downloading blessings-1.6.tar.gz 07:21:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:21:32 INFO - Installing collected packages: blessings 07:21:32 INFO - Running setup.py install for blessings 07:21:32 INFO - Successfully installed blessings 07:21:32 INFO - Cleaning up... 07:21:32 INFO - Return code: 0 07:21:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:21:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:21:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:21:32 INFO - Reading from file tmpfile_stdout 07:21:32 INFO - Current package versions: 07:21:32 INFO - blessings == 1.6 07:21:32 INFO - blobuploader == 1.2.4 07:21:32 INFO - docopt == 0.6.1 07:21:32 INFO - manifestparser == 1.1 07:21:32 INFO - mozInstall == 1.12 07:21:32 INFO - mozcrash == 0.17 07:21:32 INFO - mozdebug == 0.1 07:21:32 INFO - mozdevice == 0.48 07:21:32 INFO - mozfile == 1.2 07:21:32 INFO - mozhttpd == 0.7 07:21:32 INFO - mozinfo == 0.9 07:21:32 INFO - mozleak == 0.1 07:21:32 INFO - mozlog == 3.1 07:21:32 INFO - moznetwork == 0.27 07:21:32 INFO - mozprocess == 0.22 07:21:32 INFO - mozprofile == 0.28 07:21:32 INFO - mozrunner == 6.11 07:21:32 INFO - mozscreenshot == 0.1 07:21:32 INFO - mozsystemmonitor == 0.0 07:21:32 INFO - moztest == 0.7 07:21:32 INFO - mozversion == 1.4 07:21:32 INFO - psutil == 3.1.1 07:21:32 INFO - requests == 1.2.3 07:21:32 INFO - wsgiref == 0.1.2 07:21:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:21: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')]} 07:21:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:21:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21: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')]} 07:21:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:21:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:32 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 0x107cd6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ce6420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c7a4aabd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b75e00>, '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 0x1079dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b70b20>, '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.qWvtO6yxcT/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.vpEHavVyBJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/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 07:21:32 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 07:21:32 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 07:21:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qWvtO6yxcT/Render', 07:21:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:21:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:21:32 INFO - 'HOME': '/Users/cltbld', 07:21:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:21:32 INFO - 'LOGNAME': 'cltbld', 07:21:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:21:32 INFO - 'MOZ_NO_REMOTE': '1', 07:21:32 INFO - 'NO_EM_RESTART': '1', 07:21:32 INFO - 'PAGER': '/bin/cat', 07:21:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:21:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:21:32 INFO - 'PWD': '/builds/slave/test', 07:21:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:21:32 INFO - 'SHELL': '/bin/bash', 07:21:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vpEHavVyBJ/Listeners', 07:21:32 INFO - 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/', 07:21:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:21:32 INFO - 'USER': 'cltbld', 07:21:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:21:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:21:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:21:32 INFO - 'XPC_FLAGS': '0x0', 07:21:32 INFO - 'XPC_SERVICE_NAME': '0', 07:21:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:21:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:21:33 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)) 07:21:33 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)) 07:21:33 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)) 07:21:33 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)) 07:21:33 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)) 07:21:33 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)) 07:21:33 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)) 07:21:33 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)) 07:21:33 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)) 07:21:33 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)) 07:21:33 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)) 07:21:33 INFO - Cleaning up... 07:21:33 INFO - Return code: 0 07:21:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:21:33 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')]} 07:21:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:21:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:33 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')]} 07:21:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:21:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:33 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 0x107cd6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ce6420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c7a4aabd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b75e00>, '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 0x1079dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b70b20>, '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.qWvtO6yxcT/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.vpEHavVyBJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/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 07:21:33 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 07:21:33 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 07:21:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qWvtO6yxcT/Render', 07:21:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:21:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:21:33 INFO - 'HOME': '/Users/cltbld', 07:21:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:21:33 INFO - 'LOGNAME': 'cltbld', 07:21:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:21:33 INFO - 'MOZ_NO_REMOTE': '1', 07:21:33 INFO - 'NO_EM_RESTART': '1', 07:21:33 INFO - 'PAGER': '/bin/cat', 07:21:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:21:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:21:33 INFO - 'PWD': '/builds/slave/test', 07:21:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:21:33 INFO - 'SHELL': '/bin/bash', 07:21:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vpEHavVyBJ/Listeners', 07:21:33 INFO - 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/', 07:21:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:21:33 INFO - 'USER': 'cltbld', 07:21:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:21:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:21:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:21:33 INFO - 'XPC_FLAGS': '0x0', 07:21:33 INFO - 'XPC_SERVICE_NAME': '0', 07:21:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:21:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:21:33 INFO - Downloading/unpacking jsonschema 07:21:33 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 07:21:33 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 07:21:33 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 07:21:33 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 07:21:33 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 07:21:33 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 07:21:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:21:36 INFO - Downloading/unpacking functools32 (from jsonschema) 07:21: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 07:21: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 07:21: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 07:21: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 07:21: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 07:21: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 07:21:36 INFO - Downloading functools32-3.2.3-2.tar.gz 07:21:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:21:36 INFO - warning: no files found matching '*.txt' 07:21:36 INFO - no previously-included directories found matching 'build' 07:21:36 INFO - no previously-included directories found matching 'dist' 07:21:36 INFO - no previously-included directories found matching '.git*' 07:21:36 INFO - Installing collected packages: jsonschema, functools32 07:21:36 INFO - Running setup.py install for functools32 07:21:36 INFO - warning: no files found matching '*.txt' 07:21:36 INFO - no previously-included directories found matching 'build' 07:21:36 INFO - no previously-included directories found matching 'dist' 07:21:36 INFO - no previously-included directories found matching '.git*' 07:21:36 INFO - Successfully installed jsonschema functools32 07:21:36 INFO - Cleaning up... 07:21:36 INFO - Return code: 0 07:21:36 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 07:21:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:21:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:21:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:21:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:21:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:36 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 0x107cd6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ce6420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c7a4aabd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b75e00>, '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 0x1079dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b70b20>, '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.qWvtO6yxcT/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.vpEHavVyBJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/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 07:21:36 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 07:21:36 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 07:21:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qWvtO6yxcT/Render', 07:21:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:21:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:21:36 INFO - 'HOME': '/Users/cltbld', 07:21:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:21:36 INFO - 'LOGNAME': 'cltbld', 07:21:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:21:36 INFO - 'MOZ_NO_REMOTE': '1', 07:21:36 INFO - 'NO_EM_RESTART': '1', 07:21:36 INFO - 'PAGER': '/bin/cat', 07:21:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:21:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:21:36 INFO - 'PWD': '/builds/slave/test', 07:21:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:21:36 INFO - 'SHELL': '/bin/bash', 07:21:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vpEHavVyBJ/Listeners', 07:21:36 INFO - 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/', 07:21:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:21:36 INFO - 'USER': 'cltbld', 07:21:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:21:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:21:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:21:36 INFO - 'XPC_FLAGS': '0x0', 07:21:36 INFO - 'XPC_SERVICE_NAME': '0', 07:21:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:21:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:21:37 INFO - Downloading/unpacking flake8 07:21:37 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 07:21:37 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 07:21:37 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 07:21:37 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 07:21:37 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 07:21:37 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 07:21:39 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 07:21:39 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 07:21:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:21:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:21:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:21:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:21:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:21:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:21:39 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 07:21:39 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 07:21:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:21:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:21:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:21:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:21:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:21:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:21:40 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 07:21:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:21:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:21:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:21:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:21:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:21:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:21:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 07:21:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:21:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:21:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:21:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:21:40 INFO - no previously-included directories found matching 'docs/_build' 07:21:40 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 07:21:40 INFO - Running setup.py install for pep8 07:21:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:21:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:21:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:21:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:21:40 INFO - no previously-included directories found matching 'docs/_build' 07:21:41 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 07:21:41 INFO - Successfully installed flake8 mccabe pyflakes pep8 07:21:41 INFO - Cleaning up... 07:21:41 INFO - Return code: 0 07:21:41 INFO - Running post-action listener: _resource_record_post_action 07:21:41 INFO - Running post-action listener: _start_resource_monitoring 07:21:41 INFO - Starting resource monitoring. 07:21:41 INFO - ##### 07:21:41 INFO - ##### Running install step. 07:21:41 INFO - ##### 07:21:41 INFO - Running pre-action listener: _resource_record_pre_action 07:21:41 INFO - Running main action method: install 07:21:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:21:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:21:41 INFO - Reading from file tmpfile_stdout 07:21:41 INFO - Detecting whether we're running mozinstall >=1.0... 07:21:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:21:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:21:41 INFO - Reading from file tmpfile_stdout 07:21:41 INFO - Output received: 07:21:41 INFO - Usage: mozinstall [options] installer 07:21:41 INFO - Options: 07:21:41 INFO - -h, --help show this help message and exit 07:21:41 INFO - -d DEST, --destination=DEST 07:21:41 INFO - Directory to install application into. [default: 07:21:41 INFO - "/builds/slave/test"] 07:21:41 INFO - --app=APP Application being installed. [default: firefox] 07:21:41 INFO - mkdir: /builds/slave/test/build/application 07:21:41 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'] 07:21:41 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 07:22:12 INFO - Reading from file tmpfile_stdout 07:22:12 INFO - Output received: 07:22:12 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 07:22:12 INFO - Running post-action listener: _resource_record_post_action 07:22:12 INFO - ##### 07:22:12 INFO - ##### Running run-tests step. 07:22:12 INFO - ##### 07:22:12 INFO - Running pre-action listener: _resource_record_pre_action 07:22:12 INFO - Running main action method: run_tests 07:22:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:22:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:22:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:22:12 INFO - 2016-04-30 07:22:12.892 screenresolution[1649:8881] starting screenresolution argv=screenresolution get 07:22:12 INFO - 2016-04-30 07:22:12.895 screenresolution[1649:8881] Display 0: 1600x1200x32@60 07:22:12 INFO - 2016-04-30 07:22:12.901 screenresolution[1650:8884] starting screenresolution argv=screenresolution list 07:22:12 INFO - Available Modes on Display 0 07:22:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:22:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:22:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:22:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:22:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:22:13 INFO - Intel Iris: 07:22:13 INFO - Chipset Model: Intel Iris 07:22:13 INFO - Type: GPU 07:22:13 INFO - Bus: Built-In 07:22:13 INFO - VRAM (Dynamic, Max): 1536 MB 07:22:13 INFO - Vendor: Intel (0x8086) 07:22:13 INFO - Device ID: 0x0a2e 07:22:13 INFO - Revision ID: 0x0009 07:22:13 INFO - Displays: 07:22:13 INFO - Display: 07:22:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:22:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:22:13 INFO - Display Serial Number: 1600x1200 60 07:22:13 INFO - Main Display: Yes 07:22:13 INFO - Mirror: Off 07:22:13 INFO - Online: Yes 07:22:13 INFO - Rotation: Supported 07:22:13 INFO - Return code: 0 07:22:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:22:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:22:13 INFO - Python 2.7.3 07:22:13 INFO - Return code: 0 07:22:13 INFO - grabbing minidump binary from tooltool 07:22:13 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')]} 07:22:13 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 0x107b75e00>, '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 0x1079dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b70b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:22:13 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 07:22:13 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 07:22:13 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:22:13 INFO - Return code: 0 07:22:13 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:22:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:22:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:22:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:22:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:22:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 07:22:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 07:22:14 INFO - Return code: 0 07:22:14 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/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0200', '--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 07:22:14 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/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0200 --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 07:22:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qWvtO6yxcT/Render', 07:22:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:22:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:22:14 INFO - 'HOME': '/Users/cltbld', 07:22:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:22:14 INFO - 'LOGNAME': 'cltbld', 07:22:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:22:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:22:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:22:14 INFO - 'MOZ_NO_REMOTE': '1', 07:22:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:22:14 INFO - 'NO_EM_RESTART': '1', 07:22:14 INFO - 'PAGER': '/bin/cat', 07:22:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:22:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:22:14 INFO - 'PWD': '/builds/slave/test', 07:22:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:22:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:22:14 INFO - 'SHELL': '/bin/bash', 07:22:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vpEHavVyBJ/Listeners', 07:22:14 INFO - 'TMPDIR': '/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/', 07:22:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:22:14 INFO - 'USER': 'cltbld', 07:22:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:22:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:22:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:22:14 INFO - 'XPC_FLAGS': '0x0', 07:22:14 INFO - 'XPC_SERVICE_NAME': '0', 07:22:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:22:14 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/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0200', '--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 07:22:14 INFO - mozversion application_buildid: 20160430051339 07:22:14 INFO - mozversion application_changeset: 3f169c95e542f059b6b07d11e7f73533bdbf03d6 07:22:14 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:22:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:22:14 INFO - mozversion application_name: Firefox 07:22:14 INFO - mozversion application_remotingname: firefox-dev 07:22:14 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:22:14 INFO - mozversion application_vendor: Mozilla 07:22:14 INFO - mozversion application_version: 48.0a2 07:22:14 INFO - mozversion platform_buildid: 20160430051339 07:22:14 INFO - mozversion platform_changeset: 3f169c95e542f059b6b07d11e7f73533bdbf03d6 07:22:14 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:22:14 INFO - mozversion platform_version: 48.0a2 07:22:14 INFO - using testdate: 1462026134 07:22:14 INFO - actual date: 1462026134 07:22:14 INFO - starting webserver on 'localhost:49277' 07:22:14 INFO - SUITE-START | Running 2 tests 07:22:14 INFO - TEST-START | dromaeo_css 07:22:14 INFO - operating with platform_type : mac_ 07:22:14 INFO - Initialising browser for dromaeo_css test... 07:22:14 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/tmpuJCD6g/profile http://localhost:49277/getInfo.html) 07:22:17 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 07:22:17 INFO - PROCESS | 1668 | colorDepth:24 07:22:17 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 07:22:17 INFO - PROCESS | 1668 | __metrics 07:22:17 ERROR - PROCESS | 1668 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 07:22:18 INFO - TEST-INFO | 1668: exit 0 07:22:18 INFO - Browser initialized. 07:22:18 INFO - Running cycle 1/1 for dromaeo_css test... 07:22:18 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/tmpuJCD6g/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:22:30 INFO - PROCESS | 1672 | RSS: Main: 221667328 07:22:30 INFO - PROCESS | 1672 | 07:23:19 INFO - PROCESS | 1672 | 1462026199850 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] 07:25:30 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 07:25:32 INFO - PROCESS | 1672 | RSS: Main: 232009728 07:25:32 INFO - PROCESS | 1672 | 07:28:34 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 07:28:34 INFO - PROCESS | 1672 | RSS: Main: 243478528 07:28:34 INFO - PROCESS | 1672 | 07:31:36 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 07:31:37 INFO - PROCESS | 1672 | RSS: Main: 245846016 07:31:37 INFO - PROCESS | 1672 | 07:34:38 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 07:34:39 INFO - PROCESS | 1672 | RSS: Main: 247369728 07:34:39 INFO - PROCESS | 1672 | 07:37:42 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 07:37:42 INFO - PROCESS | 1672 | RSS: Main: 250540032 07:37:42 INFO - PROCESS | 1672 | 07:40:45 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 07:40:45 INFO - PROCESS | 1672 | RSS: Main: 251326464 07:40:45 INFO - PROCESS | 1672 | 07:40:45 INFO - PROCESS | 1672 | __start_tp_report 07:40:45 INFO - PROCESS | 1672 | _x_x_mozilla_page_load 07:40:45 INFO - PROCESS | 1672 | _x_x_mozilla_page_load_details 07:40:45 INFO - PROCESS | 1672 | |i|pagename|runs| 07:40:45 INFO - PROCESS | 1672 | |0;dojo.html;2910.854457277136;3511.438169892816;3543.645635436458;3565.0017994961413;3571.6785489305958;5736.856578585537;5755.856103597413;5773.884522309557;5796.188533605288;5797.246357973468;5759.251297331341;5811.535077193825;5816.680082595455;5837.824865254049;5840.211571437865;5180.533751962332;5240.397354304247;5282.154855223165;5297.311349524575;5302.204669299597;5125.103106956286;5231.555317797973;5238.554722848543;5242.161254199329;5269.76286067126;5226.111561034614;5235.031519168947;5246.658967167141;5248;5252;5160.587153027749;5210.296609957664;5246.186841039645;5294.205869119663;5302.92045619316;5145.176771716508;5227.320448341729;5251.448597897204;5253.264542963988;5266.368035835686;5433.483819037185;5515.751791169407;5578.274824272859;5602;5689.402612725646;5201.635885488017;6178.7219575119225;6244.718987645521;6275.780347687854;6282;6218.81343559694;6236.376362363772;6267.310595834455;6268.87462250757;6284.622922624657;5654.604177707521;5811.215485909348;5838.854028649275;5871.970640146815;5887.234659494281;6213.720382582795;6261.78083767066;6289.0880822280515;6318.905216421757;6321.241451025814;6161.907571386433;6240.875182496325;6242.75028998844;6250.15622890913;6263.937360626335;5190.792368305301;6154.815355539341;6193.473554747897;6258.123862659231;6258.655773932386;5623.971880140573;5762.905048040859;6231.034189700604;6256.7810126645345;6296.181496405438;6192.9443569266605;6254.530910181755;6256.593321434092;6266.122742816009;6271.655058971709;4585.633149348044;4856.271559266138;4901.068796928572;4967.205247160509;5004.549590536796;4407.118576284756;4415.735055896656;4455.799489023002;4456.019675671347;4495.662825288116;5809.564282678732;5841.386654401312;5854.209681692917;5862.384449632812;5872;6087.4521293083835;6172.753089876444;6186.350433204539;6216.627002379872;6237.189165408558;5812.418758124154;5818.679972601463;5824.767009319579;5833.445822646927;5842.386549412336;5334.839954801285;5449.073657478159;5453.182022696627;5454.263674403984;5468.37113731918;5176.534111929945;6259.843503912439;6365.840853978595;6380.425761681286;6443.48452123829;5680.778632594012;5777.988851950976;6216.44052035319;6232.314445411092;6253.499720022478;6201.844953876189;6413.903791443039;6415.550911436155;6430.807075787734;6435.646039467874;6158.568900176944;6246.156768836149;6270.122182894307;6272.247330320209;6293.527985401205;6212.751489940351;6245.7189426476825;6248.781292654735;6260.561760676709;6270.592411493239;5805.796797112081;6058.363871793398;6166.445019948226;6223.097650840693;6233.376662333731;4913.729744864067;5068.518490743521;5368.919466208088;6184.165137706352;6218.502519798315;5904.557158213237;5959.940400595939;6017.398260173947;6054.939450605438;6086.60437071589;6075.453209211192;6100.786472473442;6134.038624681799;6180.165677633462;6229.5327850410495;4975.825846095369;5980.401959803985;6125.47933425646;6126.295476020315;6129.387061294014 07:40:45 INFO - PROCESS | 1672 | |1;ext.html;505.4339140163018;538.281394338558;568.1960026562415;605.8485378655334;609.61289581116;36252.818735906316;40632;40672.99999999996;40693.59306406935;40718.77843664688;42020.789896050475;42841.14317713644;42848;42866;43006.13987720251;9452.669156579504;9641.517924103802;9650.613975440974;9666.4200147991;9688.709338719815;9479.43123412594;9740.561674724619;9763.414195148278;9767.120959113678;9785.55964981574;9745.610175592969;9769.120779129877;9778.559964801601;9791.412515249072;9862.506874656274;9747.074027967332;9794.804103917953;9805.950970245103;9813.460259685715;9815.50922453878;9674.177694895872;9727;9785.559649815776;9795.510224488748;9801.656942007066;33709.32581348384;40902.795486022376;41252;41400.96497587554;41416.1716765663;3185.9203519911957;3492;3686.8156592170553;3716.1824398632257;3818.102745854722;24507.877460612763;29393.559096613568;29576.40847183066;29949.85025074861;30136.54795178052;2763.4196818668106;2773.0571605654172;2816.633837601119;2822.534281843514;2828.9434211315656;378996.2100379016;380166;386105;386463.9999999972;510322;66143.33856661468;66421;66833;67094.9935750964;67162.66418667875;66364;68061.3193868055;69894;70146.298537015;70149.94775078377;18249.270029198684;18335;18487.81512184861;18497.075146242903;18520.907395462935;13868.306584670727;13929.233892135828;13963.79054314186;13981.300934953211;14000.789988150185;19342.806571934383;20613.58772824535;20884.060217290258;20964;21003.369898903056;8200.466969646954;8311.085780564134;8318.625661845232;8351.331893448509;8444.57777111142;935.4714586258742;969.8302797010494;1020.9943205942141;1027.5427434791445;1035;27241.591376129374;30587.69412305893;31096.844515777728;31208.909699931737;31568.684313156573;1076.9138468922467;1090.6291860767378;1133.2463911498926;1179.510503141204;1232.3468561662332;14407.343155537037;14408.783868241984;16148.515544533682;16220.837791622169;16573.50279491617;531513;668993;683410;693318;695107;62052.37947620556;64319.03521447182;64344.356556434774;64350.67824660847;64776.028359574644;1520.5818399940106;1525.1001908873818;1531.3644837392358;1535.439564558928;1542.2443002928708;30314.938977135695;30671.386572268875;30859.919902803293;31096.067117986495;31122;56608.43391566032;56784.86430271454;56896;57124.428755713576;57180.71409642925;492649;582334;587025;590862;591685.083149163;25638;26161.869190653924;28250.434991299648;28763.28091797722;28847;48384;48789.268160976906;50048.24927626162;50338.74830625823;50382;27117.86441067782;29319.85340073286;34463.65536344604;40379.59620403876;40605.39091913564;31268;34731;36850.44724329084;38330;39081.41377879391;38949.415758763076;39067;40448.59551404566;40604.3909341366;40765;33253;37398;39428.40857387084;39472.01319967024;39877.40183897302;29683.554746678386;30309.393812124075;30336.089917302517;30621.928232511757;30906.53640195444 07:40:45 INFO - PROCESS | 1672 | |2;jquery.html;372.888133559932;380.11433360270564;400.0877998164187;407.36247772236464;409.2592407741987;14822.703545929075;19905.303314384;20740;20754.89622551887;20895.686564701504;20052.99735013247;20066.398008059747;20935.581288374262;20954.790452095476;21284.787152128472;5772.5670574706855;5889.8822023559505;6065.848353791146;6084.604500707462;6099.48154406876;5880.647161170344;5980.132880732292;5983.790567330145;6030.547708921827;6059.333473317932;5861.648301101947;5985.551083668721;5994.160817485534;5997.820065398045;6009.188759517475;5655.293088363955;5943.910841337383;5951.732172052268;5961.940380596204;5991.910121348205;5141.383034035902;5177.637565370425;5183.533481986639;5185.896282074337;5211.765470553834;24465.63301550478;25209.62185567218;25828.225153245432;26162.215133546026;26334.6049809253;753.1113286322137;1712.9828701712947;1789.1322708486373;2157.7734337894453;2186.835987300943;14864.925675371662;15128.924355378153;15275.38898444072;15334.463293784664;15492.922535387364;1514.7349213887526;1683.0911307893723;1721.0362197169625;1733.5752740578512;1769.610685649168;117959;123384;127700.99999999907;127903;128146;9867.91452940176;10129.189664826796;10760.569577216824;10845.023947844575;11362.715932101599;13762;13767.931160344335;13939.581812545437;13973.65065873341;13983;7280.3083707047745;8848;8984.281257499384;9009.594568244445;9150.993390727015;7274.8181295466975;7307.963460182665;9011.324150688724;9219.308551858638;9245.306602004877;5773.336066352339;6077.939220607737;6275.309715931244;6616.8345791355605;6655.334466553306;1768.823117688221;1874.681304178293;1893.428454382859;1900.0689662065488;1945.406650971439;921.8110287391069;950.2065775077749;1046.9687357952412;1068.8663917010374;1083.9295445796156;20308.898455507628;21105;21143.837088960256;21313.68029479559;21494.57010859774;1027.5838285494387;1041.5313109100935;1073.302353470235;1097.1332647208617;1102.5038732570376;10899.83650245247;11371.772564548663;11382.317060976367;11393.430328483542;11417.600383986686;603491;613155;619348;624708;625974;89762.55118724365;114179;116141;117354.8264517361;119038;1063;1078.5793540519203;1079.4656644960794;1100.9614663486739;1104.2270410712533;15261.6184595386;18199.363022294157;18844.717329240346;18875.433736987914;18907.810921890607;51108;51363;51696.48303516916;51710;52190.95618087541;52788;68569.65715171392;70219.29780702332;70649;71573.28426715666;5426.891462170655;8100.068492123246;8149.225823546753;8214.507129572245;8308.418410711434;9327.160555550034;12065.396730163282;12415.37923103823;12561.937190313989;12676.04929630263;10526;10883.401412922365;11115.72210694739;11160.051395631137;11226.494807733508;10624.681259562225;10766.515506802369;11095.611653592085;11142;11320.660380188607;9668.9033109668;10991.560337586407;11195.552177912792;11218.326900386002;11491.885081149081;7934.841303173789;8184.468009579358;8239.464434811958;8349.373796965374;8626.137386261575;10904.78190436171;11064.944675276573;11208.607698730506;11216.270942388719;11416.143789216008 07:40:45 INFO - PROCESS | 1672 | |3;mootools.html;1627.94302199423;1644.1368281652133;1911.6845720456126;1959.275068224757;2012.8691635043713;8133.471324363913;8554.572271386422;8654;9040.8643870342;9047.185753282203;8623.870641940375;8659.134086591337;8796.516191609458;8804.735857924257;8805.691800786974;2130.9041093150768;2474.64117702933;2491.663625410574;2503.8998440062373;2505.1482495951373;2425.5876500994873;2456.078970386105;2474.9876250618813;2488.5645012122895;2498.7001559812848;2458.4591389894285;2469.8888550015204;2485.5028994201143;2493.7007559092935;2503;2455.6193789962585;2459.0655550890638;2465.494573612405;2465.617829236471;2489.016838348842;2356;2381.321168576167;2392.09100541793;2399.124319623342;2400.2919138853904;7582.886256706154;8857.468551886908;8880.1563851434;8897.866532002025;8906.59920303588;5302.522772950453;5509.586780991442;5519.448055194489;5527.364353099365;5565.721713914329;3565.3404120237838;3579.355715971124;3597.6222496638;3684.355237833395;3702.3705969985167;972.6401231544373;1016.1464369929221;1066.8773091094547;1068.2682362581656;1069.4706120470416;11649.70875728105;11661.70845728855;11679;11746.765064698659;11778.11664125194;4387.890302742393;4900.774806298426;4931.260310953314;4937.802487900515;5005.124103281911;10286.125679317192;10311.587536498606;10318.329308594917;10320.12278956297;10343.586256549654;5435.402105768362;5483.396826349099;5496.4503549645515;5500.7524661390335;5545.390007099216;4483.170613436525;4797.952020479751;4934.950650493449;4945.4807245238735;4970.15507363749;4115.691323150776;4170.83316667336;4172;4180.871164785552;4193.329067349209;1173.8239264110452;1342.758303505359;1394.2610416479283;1398.0632975905964;1407.1205496564646;874.925631321332;883.7613844262013;899.4423457456419;903.263839970422;917.2799352508249;4817.807287708452;5009.198528235464;5969.880602388015;5986.551008674367;6047.7278522466595;841.4446465332851;977.4477420257674;1003.603576587244;1021.22897212604;1034.2294990232174;3964.524257089168;4059.4316795648633;4102.015516276072;4155.314373128461;4175.561566035583;9166.587503562356;10974.176936729777;10987.61543345979;11041.83437248442;11057.612983545538;10826.512806923707;10918.399488028235;10953.616623418142;10971.341719496855;10979.83530247047;8005.759827205193;8217.506949583163;8319.916800832036;8338.99932008163;8368.581570921551;5812.883742325214;5923.496502797138;5985.940140598538;6066.90795656786;6202.844928876814;9878.407295562289;9988.450635214842;10207.489625518545;10221.33561318512;10231.590736370461;10309.948450257702;10321.793564128826;10327.277090603886;10366.792664146524;10389.168866490512;6364.363563643598;7070.964645176741;7192.532485388433;7203.603801790951;7224.819379515344;7064.929350706633;8374.49753014821;8496.235338819288;8512.829743405222;8514.403991720521;7300.63496825146;7462.738804141829;7498.287662672037;7538.0954285483895;7623.847523049397;7289.781306560812;7301.488895777245;7382.151052628801;7412.369948554433;7428.368588670023;7044.330788575077;7156.785296441115;7158;7173.892391614234;7190.89213661785;4904.288878112582;4923.236898280772;5028.773705183348;5037.899242015213;5076.923846142237;4329.978350108229;5000.2499625056535;5083.38999320084;5122.974385128199;5297.894042119059 07:40:45 INFO - PROCESS | 1672 | |4;prototype.html;268.45503627635907;322.39872637531;322.8514883153749;329.97195238404737;333.6246722437258;4294.549072347401;5461.3173353330785;5545;5595.356533998592;5611.783345578494;4452.777361131946;5433.375161856387;5496.587755918302;5507.256520369759;5523.972380138103;3956.821943012557;4050.51393832739;4170.499540055198;4176.791160441966;4210.7473551586845;3603.4414665726854;3862.9613703863024;4183.435236243114;4185.225733239346;4221.641160501361;3928.8017154767786;3964.2864284428792;4149.439825623547;4163.646090082347;4172.643890735508;3316.96683033169;3715.034091136297;3905.199434116028;4218.122935293795;4232.3228283474655;2788.428372183718;3610.891673249807;3613.5844377896615;3624.02151419118;3636.563612366533;4856.490068542787;5133.281340612317;5299.655522391032;5318.447145370205;5329.307190065306;4193.6645068394455;4282.571742825692;4791.880202994918;4830.975845120752;4860.756962151913;4650.674452788307;5554.472325129106;5900.468957793776;5945.7324420400755;5952.523798096142;3204.6955539223736;4001.319445832757;4004.7797371144575;4019.799010049486;4026.798660067014;4776.6417518686585;5722.456366645162;6174.228221472316;6208.689565521751;6226.595271307396;3228.596425446819;3388.4070287700047;4054.675625949917;4056.707960002589;4058.797060146981;3646.08847788053;3939.6060393960374;4127.545969943364;4128.843923701368;4141.151064031927;4805.231163013901;4817.8313759018265;4822.565969062801;4836.226203807374;4838.395200599925;5048.5203905628905;5303.178007408857;5963.045912654041;5972.790952316648;5978.252718410199;1180.527788884453;1326.4495234477583;1498.6328349554428;1510.0637604685166;1511.6523199663918;904.3850181876255;957.5496245632185;958.3483231402573;968.864358989742;988.3871999360442;5154;5880.617759845596;5898.026825573732;5907.852303692442;5910.143029260733;6315.810525684236;6439.323870993572;6504.674766261763;6541.345865413516;6610.338966103352;4600.516945720651;5184.274201611777;5548.500634942875;5563.3268054833325;5869;2877.784166187544;3125.3436778276364;3342.2479942013338;3464.72282217422;3475.3396854597545;6425.261094974137;6752.5948443093575;6829.590224586541;6908.689108990107;6944.895826562605;5381.919271210935;6645.269020407752;6702.09521714572;6735.29279425663;6820.931790682129;5952.345242023374;6287.685615719196;6334.588251763621;6768.323167683291;6911.5853048817235;5555.611097225694;5878.735456904364;6084.3915608440575;6121.693915304306;6142.477889379453;5505.697186654773;5739.741711623071;5747.425257474386;6093.817185484442;6130.908036379368;5322.121849894724;6044.33512313654;6409.33357327089;6435.356464353527;6454.903176452263;3953.327934251242;4061.756294622332;4099.569545197831;4106.548279689291;4107.240160570304;4575.222212223864;5007.273945277987;5137.280780690781;6169.660668663267;6184.752609895554;4885.3649025627865;5571;5926.585139040226;5953.523718102628;5960.092770313631;4820.855374338776;4992.8516441218;5420.322459692538;5849.239598852122;5940.435658612425;5514.448555144453;5658.632188907708;5785.218995435731;5811.476967072984;5926.140709597171;4804.399450068741;5908.261467316585;5949.553783466344;5950.851228719317;5958.603595950267;5478.767277362562;5795.565332599987;5819.883602327845;5829.59192856496;5830.484074140669 07:40:45 INFO - PROCESS | 1672 | |5;yui.html;658.2167221007002;722.9370935343371;740.7370383513852;741.4216910809564;777.9144294127645;14344.282785860705;14360.066595671275;14810;14829.777553336695;14848.1091134532;13589.660258493543;13654.52209172679;13659.521916732916;13710.588682339521;13714.862851371483;729.1760310848733;856.5845564901018;898.7797989492583;931.3061768982118;940.2102234123334;900.4147304252256;928.7213835849252;929.0802105915163;929.3958926697667;933.9112784285516;957.5403806173041;965.9613615455373;969.2585172343163;972.9221662267037;975.1029053270972;933.6638810028452;956.3783540698532;966.3090890013639;966.6954909203614;969.3505351414569;695.6556504530239;707.961062141582;716.5199316457986;729.3508777188307;730.5945200413768;14352.425902963869;14443.927780361031;14455.27723613815;14458.421663133568;14464.349104290228;10093.747656308651;12405.44175512104;12632.368381580976;12632.873671263262;12645.683857903534;9816.558254878548;9860.65487707934;10026.799464010752;10031.548580313904;10122.64570740028;3434.639362866888;3448.3620530201742;3477.0959550517045;3506.4564992426212;3665.3952097903816;72511.63744181245;75005.62497187534;75468.62265688692;75739.62130189368;76548.61725691336;7484;9470.242380609534;9481.24150067993;9531.809363812685;9635.855462168072;21020.579588408145;21092.156313747586;21123.049462773906;21170.682439763415;21200;7351.669174887143;7362.558246505227;7523.210062943422;7536.0956685198125;7598.582077985764;4045.6358927696647;4056.371262454324;4086.3461846105074;4104.322786740155;4154.127633197002;3435.3644575753565;3563.625819288989;3570.8571657133944;3587.0494319005484;3597.8560857565403;574.9712514374264;634.9365063493614;643.8358218654213;654.9214094308618;658.8583454557294;509.48796459557906;541.8943306055281;576.588337684946;580.4398755201252;598.6019297167422;7930.484518506279;8142.18578142181;8265;8265.545395003212;8303.211194936472;490.3894651159329;529.0609168725543;597.4503456819737;607.1257389359308;607.6475644126396;3839.788811615388;3944.467496887941;3951.114679059174;3983.123712783241;4132.855350062793;85025;90101.64847527293;99192.50403747935;99698.50452243222;101621.49189254154;81783;81904;82097;82272.58863705643;83717.58141209377;72039.55920881657;72241.27758722345;72386.63806680933;72521;72860.63569682224;7747.573883436465;7813.609319533887;7854.293113619801;7884.84230315379;7895.447318687637;40714.38928416017;41675.79162104291;42613.93465163272;43997;47008;51170.97658046892;54853;60569.0914636272;61138.69430652818;62270;9519.524023798644;10980.670579882617;11000.614978475714;11170.720731981766;11368.54525818958;10296.845547316647;11843.28940263587;12054.698632534257;12253.081018923438;12515.311657858905;11255.26840755381;11326.546938122383;11682.591109311134;11707.2975621463;11708.88291117112;9612.903870961201;11207;11341.376224307742;11351.432428378714;11537.59618413385;11048.723781905517;11101.056410205223;11313;11516.136289778467;11731.530738770354;9447.952760236154;9616.471094089893;9658.468784216655;9688.51557422112;9795.06946840049;9531.618735250513;9812.362196457207;9819.06718861707;9975.551100200364;10020 07:40:45 INFO - PROCESS | 1672 | __end_tp_report 07:40:45 INFO - PROCESS | 1672 | __start_cc_report 07:40:45 INFO - PROCESS | 1672 | _x_x_mozilla_cycle_collect,417 07:40:45 INFO - PROCESS | 1672 | __end_cc_report 07:40:45 INFO - PROCESS | 1672 | __startTimestamp1462027245392__endTimestamp 07:40:45 INFO - PROCESS | 1672 | 07:40:45 INFO - PROCESS | 1672 | ------- Summary: start ------- 07:40:45 INFO - PROCESS | 1672 | Number of tests: 6 07:40:45 INFO - PROCESS | 1672 | 07:40:45 INFO - PROCESS | 1672 | [#0] dojo.html Cycles:165 Average:5726.30 Median:5854.21 stddev:646.04 (11.0%) stddev-sans-first:609.10 07:40:45 INFO - PROCESS | 1672 | Values: 2910.9 3511.4 3543.6 3565.0 3571.7 5736.9 5755.9 5773.9 5796.2 5797.2 5759.3 5811.5 5816.7 5837.8 5840.2 5180.5 5240.4 5282.2 5297.3 5302.2 5125.1 5231.6 5238.6 5242.2 5269.8 5226.1 5235.0 5246.7 5248.0 5252.0 5160.6 5210.3 5246.2 5294.2 5302.9 5145.2 5227.3 5251.4 5253.3 5266.4 5433.5 5515.8 5578.3 5602.0 5689.4 5201.6 6178.7 6244.7 6275.8 6282.0 6218.8 6236.4 6267.3 6268.9 6284.6 5654.6 5811.2 5838.9 5872.0 5887.2 6213.7 6261.8 6289.1 6318.9 6321.2 6161.9 6240.9 6242.8 6250.2 6263.9 5190.8 6154.8 6193.5 6258.1 6258.7 5624.0 5762.9 6231.0 6256.8 6296.2 6192.9 6254.5 6256.6 6266.1 6271.7 4585.6 4856.3 4901.1 4967.2 5004.5 4407.1 4415.7 4455.8 4456.0 4495.7 5809.6 5841.4 5854.2 5862.4 5872.0 6087.5 6172.8 6186.4 6216.6 6237.2 5812.4 5818.7 5824.8 5833.4 5842.4 5334.8 5449.1 5453.2 5454.3 5468.4 5176.5 6259.8 6365.8 6380.4 6443.5 5680.8 5778.0 6216.4 6232.3 6253.5 6201.8 6413.9 6415.6 6430.8 6435.6 6158.6 6246.2 6270.1 6272.2 6293.5 6212.8 6245.7 6248.8 6260.6 6270.6 5805.8 6058.4 6166.4 6223.1 6233.4 4913.7 5068.5 5368.9 6184.2 6218.5 5904.6 5959.9 6017.4 6054.9 6086.6 6075.5 6100.8 6134.0 6180.2 6229.5 4975.8 5980.4 6125.5 6126.3 6129.4 07:40:45 INFO - PROCESS | 1672 | 07:40:45 INFO - PROCESS | 1672 | [#1] ext.html Cycles:180 Average:69230.84 Median:40526.49 stddev:148539.33 (366.5%) stddev-sans-first:148866.40 07:40:45 INFO - PROCESS | 1672 | Values: 505.4 538.3 568.2 605.8 609.6 36252.8 40632.0 40673.0 40693.6 40718.8 42020.8 42841.1 42848.0 42866.0 43006.1 9452.7 9641.5 9650.6 9666.4 9688.7 9479.4 9740.6 9763.4 9767.1 9785.6 9745.6 9769.1 9778.6 9791.4 9862.5 9747.1 9794.8 9806.0 9813.5 9815.5 9674.2 9727.0 9785.6 9795.5 9801.7 33709.3 40902.8 41252.0 41401.0 41416.2 3185.9 3492.0 3686.8 3716.2 3818.1 24507.9 29393.6 29576.4 29949.9 30136.5 2763.4 2773.1 2816.6 2822.5 2828.9 378996.2 380166.0 386105.0 386464.0 510322.0 66143.3 66421.0 66833.0 67095.0 67162.7 66364.0 68061.3 69894.0 70146.3 70149.9 18249.3 18335.0 18487.8 18497.1 18520.9 13868.3 13929.2 13963.8 13981.3 14000.8 19342.8 20613.6 20884.1 20964.0 21003.4 8200.5 8311.1 8318.6 8351.3 8444.6 935.5 969.8 1021.0 1027.5 1035.0 27241.6 30587.7 31096.8 31208.9 31568.7 1076.9 1090.6 1133.2 1179.5 1232.3 14407.3 14408.8 16148.5 16220.8 16573.5 531513.0 668993.0 683410.0 693318.0 695107.0 62052.4 64319.0 64344.4 64350.7 64776.0 1520.6 1525.1 1531.4 1535.4 1542.2 30314.9 30671.4 30859.9 31096.1 31122.0 56608.4 56784.9 56896.0 57124.4 57180.7 492649.0 582334.0 587025.0 590862.0 591685.1 25638.0 26161.9 28250.4 28763.3 28847.0 48384.0 48789.3 50048.2 50338.7 50382.0 27117.9 29319.9 34463.7 40379.6 40605.4 31268.0 34731.0 36850.4 38330.0 39081.4 38949.4 39067.0 40448.6 40604.4 40765.0 33253.0 37398.0 39428.4 39472.0 39877.4 29683.6 30309.4 30336.1 30621.9 30906.5 07:40:45 INFO - PROCESS | 1672 | 07:40:45 INFO - PROCESS | 1672 | [#2] jquery.html Cycles:180 Average:35021.09 Median:21029.90 stddev:102532.63 (487.6%) stddev-sans-first:102787.25 07:40:45 INFO - PROCESS | 1672 | Values: 372.9 380.1 400.1 407.4 409.3 14822.7 19905.3 20740.0 20754.9 20895.7 20053.0 20066.4 20935.6 20954.8 21284.8 5772.6 5889.9 6065.8 6084.6 6099.5 5880.6 5980.1 5983.8 6030.5 6059.3 5861.6 5985.6 5994.2 5997.8 6009.2 5655.3 5943.9 5951.7 5961.9 5991.9 5141.4 5177.6 5183.5 5185.9 5211.8 24465.6 25209.6 25828.2 26162.2 26334.6 753.1 1713.0 1789.1 2157.8 2186.8 14864.9 15128.9 15275.4 15334.5 15492.9 1514.7 1683.1 1721.0 1733.6 1769.6 117959.0 123384.0 127701.0 127903.0 128146.0 9867.9 10129.2 10760.6 10845.0 11362.7 13762.0 13767.9 13939.6 13973.7 13983.0 7280.3 8848.0 8984.3 9009.6 9151.0 7274.8 7308.0 9011.3 9219.3 9245.3 5773.3 6077.9 6275.3 6616.8 6655.3 1768.8 1874.7 1893.4 1900.1 1945.4 921.8 950.2 1047.0 1068.9 1083.9 20308.9 21105.0 21143.8 21313.7 21494.6 1027.6 1041.5 1073.3 1097.1 1102.5 10899.8 11371.8 11382.3 11393.4 11417.6 603491.0 613155.0 619348.0 624708.0 625974.0 89762.6 114179.0 116141.0 117354.8 119038.0 1063.0 1078.6 1079.5 1101.0 1104.2 15261.6 18199.4 18844.7 18875.4 18907.8 51108.0 51363.0 51696.5 51710.0 52191.0 52788.0 68569.7 70219.3 70649.0 71573.3 5426.9 8100.1 8149.2 8214.5 8308.4 9327.2 12065.4 12415.4 12561.9 12676.0 10526.0 10883.4 11115.7 11160.1 11226.5 10624.7 10766.5 11095.6 11142.0 11320.7 9668.9 10991.6 11195.6 11218.3 11491.9 7934.8 8184.5 8239.5 8349.4 8626.1 10904.8 11064.9 11208.6 11216.3 11416.1 07:40:45 INFO - PROCESS | 1672 | 07:40:45 INFO - PROCESS | 1672 | [#3] mootools.html Cycles:180 Average:5774.81 Median:4913.76 stddev:3202.49 (65.2%) stddev-sans-first:3196.31 07:40:45 INFO - PROCESS | 1672 | Values: 1627.9 1644.1 1911.7 1959.3 2012.9 8133.5 8554.6 8654.0 9040.9 9047.2 8623.9 8659.1 8796.5 8804.7 8805.7 2130.9 2474.6 2491.7 2503.9 2505.1 2425.6 2456.1 2475.0 2488.6 2498.7 2458.5 2469.9 2485.5 2493.7 2503.0 2455.6 2459.1 2465.5 2465.6 2489.0 2356.0 2381.3 2392.1 2399.1 2400.3 7582.9 8857.5 8880.2 8897.9 8906.6 5302.5 5509.6 5519.4 5527.4 5565.7 3565.3 3579.4 3597.6 3684.4 3702.4 972.6 1016.1 1066.9 1068.3 1069.5 11649.7 11661.7 11679.0 11746.8 11778.1 4387.9 4900.8 4931.3 4937.8 5005.1 10286.1 10311.6 10318.3 10320.1 10343.6 5435.4 5483.4 5496.5 5500.8 5545.4 4483.2 4798.0 4935.0 4945.5 4970.2 4115.7 4170.8 4172.0 4180.9 4193.3 1173.8 1342.8 1394.3 1398.1 1407.1 874.9 883.8 899.4 903.3 917.3 4817.8 5009.2 5969.9 5986.6 6047.7 841.4 977.4 1003.6 1021.2 1034.2 3964.5 4059.4 4102.0 4155.3 4175.6 9166.6 10974.2 10987.6 11041.8 11057.6 10826.5 10918.4 10953.6 10971.3 10979.8 8005.8 8217.5 8319.9 8339.0 8368.6 5812.9 5923.5 5985.9 6066.9 6202.8 9878.4 9988.5 10207.5 10221.3 10231.6 10309.9 10321.8 10327.3 10366.8 10389.2 6364.4 7071.0 7192.5 7203.6 7224.8 7064.9 8374.5 8496.2 8512.8 8514.4 7300.6 7462.7 7498.3 7538.1 7623.8 7289.8 7301.5 7382.2 7412.4 7428.4 7044.3 7156.8 7158.0 7173.9 7190.9 4904.3 4923.2 5028.8 5037.9 5076.9 4330.0 5000.2 5083.4 5123.0 5297.9 07:40:45 INFO - PROCESS | 1672 | 07:40:45 INFO - PROCESS | 1672 | [#4] prototype.html Cycles:180 Average:4785.64 Median:5325.71 stddev:1541.08 (28.9%) stddev-sans-first:1507.64 07:40:45 INFO - PROCESS | 1672 | Values: 268.5 322.4 322.9 330.0 333.6 4294.5 5461.3 5545.0 5595.4 5611.8 4452.8 5433.4 5496.6 5507.3 5524.0 3956.8 4050.5 4170.5 4176.8 4210.7 3603.4 3863.0 4183.4 4185.2 4221.6 3928.8 3964.3 4149.4 4163.6 4172.6 3317.0 3715.0 3905.2 4218.1 4232.3 2788.4 3610.9 3613.6 3624.0 3636.6 4856.5 5133.3 5299.7 5318.4 5329.3 4193.7 4282.6 4791.9 4831.0 4860.8 4650.7 5554.5 5900.5 5945.7 5952.5 3204.7 4001.3 4004.8 4019.8 4026.8 4776.6 5722.5 6174.2 6208.7 6226.6 3228.6 3388.4 4054.7 4056.7 4058.8 3646.1 3939.6 4127.5 4128.8 4141.2 4805.2 4817.8 4822.6 4836.2 4838.4 5048.5 5303.2 5963.0 5972.8 5978.3 1180.5 1326.4 1498.6 1510.1 1511.7 904.4 957.5 958.3 968.9 988.4 5154.0 5880.6 5898.0 5907.9 5910.1 6315.8 6439.3 6504.7 6541.3 6610.3 4600.5 5184.3 5548.5 5563.3 5869.0 2877.8 3125.3 3342.2 3464.7 3475.3 6425.3 6752.6 6829.6 6908.7 6944.9 5381.9 6645.3 6702.1 6735.3 6820.9 5952.3 6287.7 6334.6 6768.3 6911.6 5555.6 5878.7 6084.4 6121.7 6142.5 5505.7 5739.7 5747.4 6093.8 6130.9 5322.1 6044.3 6409.3 6435.4 6454.9 3953.3 4061.8 4099.6 4106.5 4107.2 4575.2 5007.3 5137.3 6169.7 6184.8 4885.4 5571.0 5926.6 5953.5 5960.1 4820.9 4992.9 5420.3 5849.2 5940.4 5514.4 5658.6 5785.2 5811.5 5926.1 4804.4 5908.3 5949.6 5950.9 5958.6 5478.8 5795.6 5819.9 5829.6 5830.5 07:40:45 INFO - PROCESS | 1672 | 07:40:45 INFO - PROCESS | 1672 | [#5] yui.html Cycles:180 Average:17852.38 Median:607.39 stddev:25344.06 (4172.6%) stddev-sans-first:25382.27 07:40:45 INFO - PROCESS | 1672 | Values: 658.2 722.9 740.7 741.4 777.9 14344.3 14360.1 14810.0 14829.8 14848.1 13589.7 13654.5 13659.5 13710.6 13714.9 729.2 856.6 898.8 931.3 940.2 900.4 928.7 929.1 929.4 933.9 957.5 966.0 969.3 972.9 975.1 933.7 956.4 966.3 966.7 969.4 695.7 708.0 716.5 729.4 730.6 14352.4 14443.9 14455.3 14458.4 14464.3 10093.7 12405.4 12632.4 12632.9 12645.7 9816.6 9860.7 10026.8 10031.5 10122.6 3434.6 3448.4 3477.1 3506.5 3665.4 72511.6 75005.6 75468.6 75739.6 76548.6 7484.0 9470.2 9481.2 9531.8 9635.9 21020.6 21092.2 21123.0 21170.7 21200.0 7351.7 7362.6 7523.2 7536.1 7598.6 4045.6 4056.4 4086.3 4104.3 4154.1 3435.4 3563.6 3570.9 3587.0 3597.9 575.0 634.9 643.8 654.9 658.9 509.5 541.9 576.6 580.4 598.6 7930.5 8142.2 8265.0 8265.5 8303.2 490.4 529.1 597.5 607.1 607.6 3839.8 3944.5 3951.1 3983.1 4132.9 85025.0 90101.6 99192.5 99698.5 101621.5 81783.0 81904.0 82097.0 82272.6 83717.6 72039.6 72241.3 72386.6 72521.0 72860.6 7747.6 7813.6 7854.3 7884.8 7895.4 40714.4 41675.8 42613.9 43997.0 47008.0 51171.0 54853.0 60569.1 61138.7 62270.0 9519.5 10980.7 11000.6 11170.7 11368.5 10296.8 11843.3 12054.7 12253.1 12515.3 11255.3 11326.5 11682.6 11707.3 11708.9 9612.9 11207.0 11341.4 11351.4 11537.6 11048.7 11101.1 11313.0 11516.1 11731.5 9448.0 9616.5 9658.5 9688.5 9795.1 9531.6 9812.4 9819.1 9975.6 10020.0 07:40:45 INFO - PROCESS | 1672 | -------- Summary: end -------- 07:40:45 INFO - PROCESS | 1672 | 07:40:45 INFO - TEST-INFO | 1672: exit 0 07:40:45 INFO - TEST-OK | dromaeo_css | took 1111402ms 07:40:45 INFO - TEST-START | kraken 07:40:45 INFO - operating with platform_type : mac_ 07:40:45 INFO - Initialising browser for kraken test... 07:40:45 INFO - TEST-INFO | started process 1681 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/tmp13cgtw/profile http://localhost:49277/getInfo.html) 07:40:48 INFO - PROCESS | 1681 | __metrics Screen width/height:1600/1200 07:40:48 INFO - PROCESS | 1681 | colorDepth:24 07:40:48 INFO - PROCESS | 1681 | Browser inner width/height: 1024/689 07:40:48 INFO - PROCESS | 1681 | __metrics 07:40:48 ERROR - PROCESS | 1681 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 07:40:49 INFO - TEST-INFO | 1681: exit 0 07:40:49 INFO - Browser initialized. 07:40:49 INFO - Running cycle 1/1 for kraken test... 07:40:49 INFO - TEST-INFO | started process 1684 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/T/tmp13cgtw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:41:01 INFO - PROCESS | 1684 | RSS: Main: 221736960 07:41:01 INFO - PROCESS | 1684 | 07:41:03 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 07:41:04 INFO - PROCESS | 1684 | RSS: Main: 233844736 07:41:04 INFO - PROCESS | 1684 | 07:41:07 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 07:41:08 INFO - PROCESS | 1684 | RSS: Main: 243044352 07:41:08 INFO - PROCESS | 1684 | 07:41:11 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 07:41:12 INFO - PROCESS | 1684 | RSS: Main: 246849536 07:41:12 INFO - PROCESS | 1684 | 07:41:14 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 07:41:14 INFO - PROCESS | 1684 | RSS: Main: 247291904 07:41:14 INFO - PROCESS | 1684 | 07:41:16 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:41:17 INFO - PROCESS | 1684 | RSS: Main: 247549952 07:41:17 INFO - PROCESS | 1684 | 07:41:20 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 07:41:20 INFO - PROCESS | 1684 | RSS: Main: 247791616 07:41:20 INFO - PROCESS | 1684 | 07:41:23 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 07:41:24 INFO - PROCESS | 1684 | RSS: Main: 248795136 07:41:24 INFO - PROCESS | 1684 | 07:41:27 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 07:41:28 INFO - PROCESS | 1684 | RSS: Main: 249106432 07:41:28 INFO - PROCESS | 1684 | 07:41:29 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:41:30 INFO - PROCESS | 1684 | RSS: Main: 248930304 07:41:30 INFO - PROCESS | 1684 | 07:41:32 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:41:32 INFO - PROCESS | 1684 | RSS: Main: 248467456 07:41:32 INFO - PROCESS | 1684 | 07:41:34 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:41:35 INFO - PROCESS | 1684 | RSS: Main: 248647680 07:41:35 INFO - PROCESS | 1684 | 07:41:38 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:41:39 INFO - PROCESS | 1684 | RSS: Main: 248647680 07:41:39 INFO - PROCESS | 1684 | 07:41:42 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:41:42 INFO - PROCESS | 1684 | RSS: Main: 248676352 07:41:42 INFO - PROCESS | 1684 | 07:41:44 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 07:41:44 INFO - PROCESS | 1684 | RSS: Main: 250281984 07:41:44 INFO - PROCESS | 1684 | 07:41:44 INFO - PROCESS | 1684 | __start_tp_report 07:41:44 INFO - PROCESS | 1684 | _x_x_mozilla_page_load 07:41:44 INFO - PROCESS | 1684 | _x_x_mozilla_page_load_details 07:41:44 INFO - PROCESS | 1684 | |i|pagename|runs| 07:41:44 INFO - PROCESS | 1684 | |0;ai-astar;118;117;117;119;118;132;116;118;117;114 07:41:44 INFO - PROCESS | 1684 | |1;audio-beat-detection;151;136;186;178;119;136;123;152;154;125 07:41:44 INFO - PROCESS | 1684 | |2;audio-dft;199;192;230;261;192;196;194;199;226;275 07:41:44 INFO - PROCESS | 1684 | |3;audio-fft;63;65;80;141;55;56;57;62;125;56 07:41:44 INFO - PROCESS | 1684 | |4;audio-oscillator;77;80;134;78;78;78;116;79;82;118 07:41:44 INFO - PROCESS | 1684 | |5;imaging-gaussian-blur;89;87;109;86;90;86;90;86;88;86 07:41:44 INFO - PROCESS | 1684 | |6;imaging-darkroom;95;95;95;111;153;95;94;95;96;117 07:41:44 INFO - PROCESS | 1684 | |7;imaging-desaturate;142;115;89;110;144;111;93;89;90;92 07:41:44 INFO - PROCESS | 1684 | |8;json-parse-financial;62;66;67;64;65;66;66;79;61;59 07:41:44 INFO - PROCESS | 1684 | |9;json-stringify-tinderbox;64;65;65;69;74;74;69;69;67;71 07:41:44 INFO - PROCESS | 1684 | |10;stanford-crypto-aes;83;84;83;83;84;83;84;83;87;104 07:41:44 INFO - PROCESS | 1684 | |11;stanford-crypto-ccm;155;163;157;135;140;158;137;136;184;161 07:41:44 INFO - PROCESS | 1684 | |12;stanford-crypto-pbkdf2;172;184;224;177;171;170;167;169;174;168 07:41:44 INFO - PROCESS | 1684 | |13;stanford-crypto-sha256-iterative;67;63;74;70;67;70;69;69;72;72 07:41:44 INFO - PROCESS | 1684 | __end_tp_report 07:41:44 INFO - PROCESS | 1684 | __start_cc_report 07:41:44 INFO - PROCESS | 1684 | _x_x_mozilla_cycle_collect,1087 07:41:44 INFO - PROCESS | 1684 | __end_cc_report 07:41:44 INFO - PROCESS | 1684 | __startTimestamp1462027304616__endTimestamp 07:41:44 INFO - PROCESS | 1684 | 07:41:44 INFO - PROCESS | 1684 | ------- Summary: start ------- 07:41:44 INFO - PROCESS | 1684 | Number of tests: 14 07:41:44 INFO - PROCESS | 1684 | 07:41:44 INFO - PROCESS | 1684 | [#0] ai-astar Cycles:10 Average:118.60 Median:118.00 stddev:4.90 (4.2%) stddev-sans-first:5.20 07:41:44 INFO - PROCESS | 1684 | Values: 118.0 117.0 117.0 119.0 118.0 132.0 116.0 118.0 117.0 114.0 07:41:44 INFO - PROCESS | 1684 | 07:41:44 INFO - PROCESS | 1684 | [#1] audio-beat-detection Cycles:10 Average:146.00 Median:151.50 stddev:22.73 (15.0%) stddev-sans-first:24.03 07:41:44 INFO - PROCESS | 1684 | Values: 151.0 136.0 186.0 178.0 119.0 136.0 123.0 152.0 154.0 125.0 07:41:44 INFO - PROCESS | 1684 | 07:41:44 INFO - PROCESS | 1684 | [#2] audio-dft Cycles:10 Average:216.40 Median:212.50 stddev:30.58 (14.4%) stddev-sans-first:31.78 07:41:44 INFO - PROCESS | 1684 | Values: 199.0 192.0 230.0 261.0 192.0 196.0 194.0 199.0 226.0 275.0 07:41:44 INFO - PROCESS | 1684 | 07:41:44 INFO - PROCESS | 1684 | [#3] audio-fft Cycles:10 Average:76.00 Median:59.50 stddev:31.14 (52.3%) stddev-sans-first:32.68 07:41:44 INFO - PROCESS | 1684 | Values: 63.0 65.0 80.0 141.0 55.0 56.0 57.0 62.0 125.0 56.0 07:41:44 INFO - PROCESS | 1684 | 07:41:44 INFO - PROCESS | 1684 | [#4] audio-oscillator Cycles:10 Average:92.00 Median:78.00 stddev:21.71 (27.8%) stddev-sans-first:22.34 07:41:44 INFO - PROCESS | 1684 | Values: 77.0 80.0 134.0 78.0 78.0 78.0 116.0 79.0 82.0 118.0 07:41:44 INFO - PROCESS | 1684 | 07:41:44 INFO - PROCESS | 1684 | [#5] imaging-gaussian-blur Cycles:10 Average:89.70 Median:87.50 stddev:6.98 (8.0%) stddev-sans-first:7.40 07:41:44 INFO - PROCESS | 1684 | Values: 89.0 87.0 109.0 86.0 90.0 86.0 90.0 86.0 88.0 86.0 07:41:44 INFO - PROCESS | 1684 | 07:41:44 INFO - PROCESS | 1684 | [#6] imaging-darkroom Cycles:10 Average:104.60 Median:95.00 stddev:18.81 (19.8%) stddev-sans-first:19.63 07:41:44 INFO - PROCESS | 1684 | Values: 95.0 95.0 95.0 111.0 153.0 95.0 94.0 95.0 96.0 117.0 07:41:44 INFO - PROCESS | 1684 | 07:41:44 INFO - PROCESS | 1684 | [#7] imaging-desaturate Cycles:10 Average:107.50 Median:89.00 stddev:21.18 (23.8%) stddev-sans-first:18.43 07:41:44 INFO - PROCESS | 1684 | Values: 142.0 115.0 89.0 110.0 144.0 111.0 93.0 89.0 90.0 92.0 07:41:44 INFO - PROCESS | 1684 | 07:41:44 INFO - PROCESS | 1684 | [#8] json-parse-financial Cycles:10 Average:65.50 Median:66.00 stddev:5.40 (8.2%) stddev-sans-first:5.58 07:41:44 INFO - PROCESS | 1684 | Values: 62.0 66.0 67.0 64.0 65.0 66.0 66.0 79.0 61.0 59.0 07:41:44 INFO - PROCESS | 1684 | 07:41:44 INFO - PROCESS | 1684 | [#9] json-stringify-tinderbox Cycles:10 Average:68.70 Median:69.00 stddev:3.56 (5.2%) stddev-sans-first:3.35 07:41:44 INFO - PROCESS | 1684 | Values: 64.0 65.0 65.0 69.0 74.0 74.0 69.0 69.0 67.0 71.0 07:41:44 INFO - PROCESS | 1684 | 07:41:44 INFO - PROCESS | 1684 | [#10] stanford-crypto-aes Cycles:10 Average:85.80 Median:83.50 stddev:6.51 (7.8%) stddev-sans-first:6.83 07:41:44 INFO - PROCESS | 1684 | Values: 83.0 84.0 83.0 83.0 84.0 83.0 84.0 83.0 87.0 104.0 07:41:44 INFO - PROCESS | 1684 | 07:41:44 INFO - PROCESS | 1684 | [#11] stanford-crypto-ccm Cycles:10 Average:152.60 Median:157.50 stddev:15.66 (9.9%) stddev-sans-first:16.58 07:41:44 INFO - PROCESS | 1684 | Values: 155.0 163.0 157.0 135.0 140.0 158.0 137.0 136.0 184.0 161.0 07:41:44 INFO - PROCESS | 1684 | 07:41:44 INFO - PROCESS | 1684 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.60 Median:173.00 stddev:17.06 (9.9%) stddev-sans-first:17.97 07:41:44 INFO - PROCESS | 1684 | Values: 172.0 184.0 224.0 177.0 171.0 170.0 167.0 169.0 174.0 168.0 07:41:44 INFO - PROCESS | 1684 | 07:41:44 INFO - PROCESS | 1684 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.30 Median:70.00 stddev:3.13 (4.5%) stddev-sans-first:3.21 07:41:44 INFO - PROCESS | 1684 | Values: 67.0 63.0 74.0 70.0 67.0 70.0 69.0 69.0 72.0 72.0 07:41:44 INFO - PROCESS | 1684 | -------- Summary: end -------- 07:41:44 INFO - PROCESS | 1684 | 07:41:45 INFO - TEST-INFO | 1684: exit 0 07:41:45 INFO - TEST-OK | kraken | took 59401ms 07:41:45 INFO - SUITE-END | took 1170s 07:41:45 INFO - Completed test suite (00:19:31) 07:41:45 INFO - javascript benchmark 07:41:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2910.854457277136, 3511.438169892816, 3543.645635436458, 3565.0017994961413, 3571.6785489305958, 5736.856578585537, 5755.856103597413, 5773.884522309557, 5796.188533605288, 5797.246357973468, 5759.251297331341, 5811.535077193825, 5816.680082595455, 5837.824865254049, 5840.211571437865, 5180.533751962332, 5240.397354304247, 5282.154855223165, 5297.311349524575, 5302.204669299597, 5125.103106956286, 5231.555317797973, 5238.554722848543, 5242.161254199329, 5269.76286067126, 5226.111561034614, 5235.031519168947, 5246.658967167141, 5248.0, 5252.0, 5160.587153027749, 5210.296609957664, 5246.186841039645, 5294.205869119663, 5302.92045619316, 5145.176771716508, 5227.320448341729, 5251.448597897204, 5253.264542963988, 5266.368035835686, 5433.483819037185, 5515.751791169407, 5578.274824272859, 5602.0, 5689.402612725646, 5201.635885488017, 6178.7219575119225, 6244.718987645521, 6275.780347687854, 6282.0, 6218.81343559694, 6236.376362363772, 6267.310595834455, 6268.87462250757, 6284.622922624657, 5654.604177707521, 5811.215485909348, 5838.854028649275, 5871.970640146815, 5887.234659494281, 6213.720382582795, 6261.78083767066, 6289.0880822280515, 6318.905216421757, 6321.241451025814, 6161.907571386433, 6240.875182496325, 6242.75028998844, 6250.15622890913, 6263.937360626335, 5190.792368305301, 6154.815355539341, 6193.473554747897, 6258.123862659231, 6258.655773932386, 5623.971880140573, 5762.905048040859, 6231.034189700604, 6256.7810126645345, 6296.181496405438, 6192.9443569266605, 6254.530910181755, 6256.593321434092, 6266.122742816009, 6271.655058971709, 4585.633149348044, 4856.271559266138, 4901.068796928572, 4967.205247160509, 5004.549590536796, 4407.118576284756, 4415.735055896656, 4455.799489023002, 4456.019675671347, 4495.662825288116, 5809.564282678732, 5841.386654401312, 5854.209681692917, 5862.384449632812, 5872.0, 6087.4521293083835, 6172.753089876444, 6186.350433204539, 6216.627002379872, 6237.189165408558, 5812.418758124154, 5818.679972601463, 5824.767009319579, 5833.445822646927, 5842.386549412336, 5334.839954801285, 5449.073657478159, 5453.182022696627, 5454.263674403984, 5468.37113731918, 5176.534111929945, 6259.843503912439, 6365.840853978595, 6380.425761681286, 6443.48452123829, 5680.778632594012, 5777.988851950976, 6216.44052035319, 6232.314445411092, 6253.499720022478, 6201.844953876189, 6413.903791443039, 6415.550911436155, 6430.807075787734, 6435.646039467874, 6158.568900176944, 6246.156768836149, 6270.122182894307, 6272.247330320209, 6293.527985401205, 6212.751489940351, 6245.7189426476825, 6248.781292654735, 6260.561760676709, 6270.592411493239, 5805.796797112081, 6058.363871793398, 6166.445019948226, 6223.097650840693, 6233.376662333731, 4913.729744864067, 5068.518490743521, 5368.919466208088, 6184.165137706352, 6218.502519798315, 5904.557158213237, 5959.940400595939, 6017.398260173947, 6054.939450605438, 6086.60437071589, 6075.453209211192, 6100.786472473442, 6134.038624681799, 6180.165677633462, 6229.5327850410495, 4975.825846095369, 5980.401959803985, 6125.47933425646, 6126.295476020315, 6129.387061294014], "value": 5687.669624398081, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [505.4339140163018, 538.281394338558, 568.1960026562415, 605.8485378655334, 609.61289581116, 36252.818735906316, 40632.0, 40672.99999999996, 40693.59306406935, 40718.77843664688, 42020.789896050475, 42841.14317713644, 42848.0, 42866.0, 43006.13987720251, 9452.669156579504, 9641.517924103802, 9650.613975440974, 9666.4200147991, 9688.709338719815, 9479.43123412594, 9740.561674724619, 9763.414195148278, 9767.120959113678, 9785.55964981574, 9745.610175592969, 9769.120779129877, 9778.559964801601, 9791.412515249072, 9862.506874656274, 9747.074027967332, 9794.804103917953, 9805.950970245103, 9813.460259685715, 9815.50922453878, 9674.177694895872, 9727.0, 9785.559649815776, 9795.510224488748, 9801.656942007066, 33709.32581348384, 40902.795486022376, 41252.0, 41400.96497587554, 41416.1716765663, 3185.9203519911957, 3492.0, 3686.8156592170553, 3716.1824398632257, 3818.102745854722, 24507.877460612763, 29393.559096613568, 29576.40847183066, 29949.85025074861, 30136.54795178052, 2763.4196818668106, 2773.0571605654172, 2816.633837601119, 2822.534281843514, 2828.9434211315656, 378996.2100379016, 380166.0, 386105.0, 386463.9999999972, 510322.0, 66143.33856661468, 66421.0, 66833.0, 67094.9935750964, 67162.66418667875, 66364.0, 68061.3193868055, 69894.0, 70146.298537015, 70149.94775078377, 18249.270029198684, 18335.0, 18487.81512184861, 18497.075146242903, 18520.907395462935, 13868.306584670727, 13929.233892135828, 13963.79054314186, 13981.300934953211, 14000.789988150185, 19342.806571934383, 20613.58772824535, 20884.060217290258, 20964.0, 21003.369898903056, 8200.466969646954, 8311.085780564134, 8318.625661845232, 8351.331893448509, 8444.57777111142, 935.4714586258742, 969.8302797010494, 1020.9943205942141, 1027.5427434791445, 1035.0, 27241.591376129374, 30587.69412305893, 31096.844515777728, 31208.909699931737, 31568.684313156573, 1076.9138468922467, 1090.6291860767378, 1133.2463911498926, 1179.510503141204, 1232.3468561662332, 14407.343155537037, 14408.783868241984, 16148.515544533682, 16220.837791622169, 16573.50279491617, 531513.0, 668993.0, 683410.0, 693318.0, 695107.0, 62052.37947620556, 64319.03521447182, 64344.356556434774, 64350.67824660847, 64776.028359574644, 1520.5818399940106, 1525.1001908873818, 1531.3644837392358, 1535.439564558928, 1542.2443002928708, 30314.938977135695, 30671.386572268875, 30859.919902803293, 31096.067117986495, 31122.0, 56608.43391566032, 56784.86430271454, 56896.0, 57124.428755713576, 57180.71409642925, 492649.0, 582334.0, 587025.0, 590862.0, 591685.083149163, 25638.0, 26161.869190653924, 28250.434991299648, 28763.28091797722, 28847.0, 48384.0, 48789.268160976906, 50048.24927626162, 50338.74830625823, 50382.0, 27117.86441067782, 29319.85340073286, 34463.65536344604, 40379.59620403876, 40605.39091913564, 31268.0, 34731.0, 36850.44724329084, 38330.0, 39081.41377879391, 38949.415758763076, 39067.0, 40448.59551404566, 40604.3909341366, 40765.0, 33253.0, 37398.0, 39428.40857387084, 39472.01319967024, 39877.40183897302, 29683.554746678386, 30309.393812124075, 30336.089917302517, 30621.928232511757, 30906.53640195444], "value": 20639.528227023075, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.888133559932, 380.11433360270564, 400.0877998164187, 407.36247772236464, 409.2592407741987, 14822.703545929075, 19905.303314384, 20740.0, 20754.89622551887, 20895.686564701504, 20052.99735013247, 20066.398008059747, 20935.581288374262, 20954.790452095476, 21284.787152128472, 5772.5670574706855, 5889.8822023559505, 6065.848353791146, 6084.604500707462, 6099.48154406876, 5880.647161170344, 5980.132880732292, 5983.790567330145, 6030.547708921827, 6059.333473317932, 5861.648301101947, 5985.551083668721, 5994.160817485534, 5997.820065398045, 6009.188759517475, 5655.293088363955, 5943.910841337383, 5951.732172052268, 5961.940380596204, 5991.910121348205, 5141.383034035902, 5177.637565370425, 5183.533481986639, 5185.896282074337, 5211.765470553834, 24465.63301550478, 25209.62185567218, 25828.225153245432, 26162.215133546026, 26334.6049809253, 753.1113286322137, 1712.9828701712947, 1789.1322708486373, 2157.7734337894453, 2186.835987300943, 14864.925675371662, 15128.924355378153, 15275.38898444072, 15334.463293784664, 15492.922535387364, 1514.7349213887526, 1683.0911307893723, 1721.0362197169625, 1733.5752740578512, 1769.610685649168, 117959.0, 123384.0, 127700.99999999907, 127903.0, 128146.0, 9867.91452940176, 10129.189664826796, 10760.569577216824, 10845.023947844575, 11362.715932101599, 13762.0, 13767.931160344335, 13939.581812545437, 13973.65065873341, 13983.0, 7280.3083707047745, 8848.0, 8984.281257499384, 9009.594568244445, 9150.993390727015, 7274.8181295466975, 7307.963460182665, 9011.324150688724, 9219.308551858638, 9245.306602004877, 5773.336066352339, 6077.939220607737, 6275.309715931244, 6616.8345791355605, 6655.334466553306, 1768.823117688221, 1874.681304178293, 1893.428454382859, 1900.0689662065488, 1945.406650971439, 921.8110287391069, 950.2065775077749, 1046.9687357952412, 1068.8663917010374, 1083.9295445796156, 20308.898455507628, 21105.0, 21143.837088960256, 21313.68029479559, 21494.57010859774, 1027.5838285494387, 1041.5313109100935, 1073.302353470235, 1097.1332647208617, 1102.5038732570376, 10899.83650245247, 11371.772564548663, 11382.317060976367, 11393.430328483542, 11417.600383986686, 603491.0, 613155.0, 619348.0, 624708.0, 625974.0, 89762.55118724365, 114179.0, 116141.0, 117354.8264517361, 119038.0, 1063.0, 1078.5793540519203, 1079.4656644960794, 1100.9614663486739, 1104.2270410712533, 15261.6184595386, 18199.363022294157, 18844.717329240346, 18875.433736987914, 18907.810921890607, 51108.0, 51363.0, 51696.48303516916, 51710.0, 52190.95618087541, 52788.0, 68569.65715171392, 70219.29780702332, 70649.0, 71573.28426715666, 5426.891462170655, 8100.068492123246, 8149.225823546753, 8214.507129572245, 8308.418410711434, 9327.160555550034, 12065.396730163282, 12415.37923103823, 12561.937190313989, 12676.04929630263, 10526.0, 10883.401412922365, 11115.72210694739, 11160.051395631137, 11226.494807733508, 10624.681259562225, 10766.515506802369, 11095.611653592085, 11142.0, 11320.660380188607, 9668.9033109668, 10991.560337586407, 11195.552177912792, 11218.326900386002, 11491.885081149081, 7934.841303173789, 8184.468009579358, 8239.464434811958, 8349.373796965374, 8626.137386261575, 10904.78190436171, 11064.944675276573, 11208.607698730506, 11216.270942388719, 11416.143789216008], "value": 9613.856230740805, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1627.94302199423, 1644.1368281652133, 1911.6845720456126, 1959.275068224757, 2012.8691635043713, 8133.471324363913, 8554.572271386422, 8654.0, 9040.8643870342, 9047.185753282203, 8623.870641940375, 8659.134086591337, 8796.516191609458, 8804.735857924257, 8805.691800786974, 2130.9041093150768, 2474.64117702933, 2491.663625410574, 2503.8998440062373, 2505.1482495951373, 2425.5876500994873, 2456.078970386105, 2474.9876250618813, 2488.5645012122895, 2498.7001559812848, 2458.4591389894285, 2469.8888550015204, 2485.5028994201143, 2493.7007559092935, 2503.0, 2455.6193789962585, 2459.0655550890638, 2465.494573612405, 2465.617829236471, 2489.016838348842, 2356.0, 2381.321168576167, 2392.09100541793, 2399.124319623342, 2400.2919138853904, 7582.886256706154, 8857.468551886908, 8880.1563851434, 8897.866532002025, 8906.59920303588, 5302.522772950453, 5509.586780991442, 5519.448055194489, 5527.364353099365, 5565.721713914329, 3565.3404120237838, 3579.355715971124, 3597.6222496638, 3684.355237833395, 3702.3705969985167, 972.6401231544373, 1016.1464369929221, 1066.8773091094547, 1068.2682362581656, 1069.4706120470416, 11649.70875728105, 11661.70845728855, 11679.0, 11746.765064698659, 11778.11664125194, 4387.890302742393, 4900.774806298426, 4931.260310953314, 4937.802487900515, 5005.124103281911, 10286.125679317192, 10311.587536498606, 10318.329308594917, 10320.12278956297, 10343.586256549654, 5435.402105768362, 5483.396826349099, 5496.4503549645515, 5500.7524661390335, 5545.390007099216, 4483.170613436525, 4797.952020479751, 4934.950650493449, 4945.4807245238735, 4970.15507363749, 4115.691323150776, 4170.83316667336, 4172.0, 4180.871164785552, 4193.329067349209, 1173.8239264110452, 1342.758303505359, 1394.2610416479283, 1398.0632975905964, 1407.1205496564646, 874.925631321332, 883.7613844262013, 899.4423457456419, 903.263839970422, 917.2799352508249, 4817.807287708452, 5009.198528235464, 5969.880602388015, 5986.551008674367, 6047.7278522466595, 841.4446465332851, 977.4477420257674, 1003.603576587244, 1021.22897212604, 1034.2294990232174, 3964.524257089168, 4059.4316795648633, 4102.015516276072, 4155.314373128461, 4175.561566035583, 9166.587503562356, 10974.176936729777, 10987.61543345979, 11041.83437248442, 11057.612983545538, 10826.512806923707, 10918.399488028235, 10953.616623418142, 10971.341719496855, 10979.83530247047, 8005.759827205193, 8217.506949583163, 8319.916800832036, 8338.99932008163, 8368.581570921551, 5812.883742325214, 5923.496502797138, 5985.940140598538, 6066.90795656786, 6202.844928876814, 9878.407295562289, 9988.450635214842, 10207.489625518545, 10221.33561318512, 10231.590736370461, 10309.948450257702, 10321.793564128826, 10327.277090603886, 10366.792664146524, 10389.168866490512, 6364.363563643598, 7070.964645176741, 7192.532485388433, 7203.603801790951, 7224.819379515344, 7064.929350706633, 8374.49753014821, 8496.235338819288, 8512.829743405222, 8514.403991720521, 7300.63496825146, 7462.738804141829, 7498.287662672037, 7538.0954285483895, 7623.847523049397, 7289.781306560812, 7301.488895777245, 7382.151052628801, 7412.369948554433, 7428.368588670023, 7044.330788575077, 7156.785296441115, 7158.0, 7173.892391614234, 7190.89213661785, 4904.288878112582, 4923.236898280772, 5028.773705183348, 5037.899242015213, 5076.923846142237, 4329.978350108229, 5000.2499625056535, 5083.38999320084, 5122.974385128199, 5297.894042119059], "value": 4671.586158445453, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [268.45503627635907, 322.39872637531, 322.8514883153749, 329.97195238404737, 333.6246722437258, 4294.549072347401, 5461.3173353330785, 5545.0, 5595.356533998592, 5611.783345578494, 4452.777361131946, 5433.375161856387, 5496.587755918302, 5507.256520369759, 5523.972380138103, 3956.821943012557, 4050.51393832739, 4170.499540055198, 4176.791160441966, 4210.7473551586845, 3603.4414665726854, 3862.9613703863024, 4183.435236243114, 4185.225733239346, 4221.641160501361, 3928.8017154767786, 3964.2864284428792, 4149.439825623547, 4163.646090082347, 4172.643890735508, 3316.96683033169, 3715.034091136297, 3905.199434116028, 4218.122935293795, 4232.3228283474655, 2788.428372183718, 3610.891673249807, 3613.5844377896615, 3624.02151419118, 3636.563612366533, 4856.490068542787, 5133.281340612317, 5299.655522391032, 5318.447145370205, 5329.307190065306, 4193.6645068394455, 4282.571742825692, 4791.880202994918, 4830.975845120752, 4860.756962151913, 4650.674452788307, 5554.472325129106, 5900.468957793776, 5945.7324420400755, 5952.523798096142, 3204.6955539223736, 4001.319445832757, 4004.7797371144575, 4019.799010049486, 4026.798660067014, 4776.6417518686585, 5722.456366645162, 6174.228221472316, 6208.689565521751, 6226.595271307396, 3228.596425446819, 3388.4070287700047, 4054.675625949917, 4056.707960002589, 4058.797060146981, 3646.08847788053, 3939.6060393960374, 4127.545969943364, 4128.843923701368, 4141.151064031927, 4805.231163013901, 4817.8313759018265, 4822.565969062801, 4836.226203807374, 4838.395200599925, 5048.5203905628905, 5303.178007408857, 5963.045912654041, 5972.790952316648, 5978.252718410199, 1180.527788884453, 1326.4495234477583, 1498.6328349554428, 1510.0637604685166, 1511.6523199663918, 904.3850181876255, 957.5496245632185, 958.3483231402573, 968.864358989742, 988.3871999360442, 5154.0, 5880.617759845596, 5898.026825573732, 5907.852303692442, 5910.143029260733, 6315.810525684236, 6439.323870993572, 6504.674766261763, 6541.345865413516, 6610.338966103352, 4600.516945720651, 5184.274201611777, 5548.500634942875, 5563.3268054833325, 5869.0, 2877.784166187544, 3125.3436778276364, 3342.2479942013338, 3464.72282217422, 3475.3396854597545, 6425.261094974137, 6752.5948443093575, 6829.590224586541, 6908.689108990107, 6944.895826562605, 5381.919271210935, 6645.269020407752, 6702.09521714572, 6735.29279425663, 6820.931790682129, 5952.345242023374, 6287.685615719196, 6334.588251763621, 6768.323167683291, 6911.5853048817235, 5555.611097225694, 5878.735456904364, 6084.3915608440575, 6121.693915304306, 6142.477889379453, 5505.697186654773, 5739.741711623071, 5747.425257474386, 6093.817185484442, 6130.908036379368, 5322.121849894724, 6044.33512313654, 6409.33357327089, 6435.356464353527, 6454.903176452263, 3953.327934251242, 4061.756294622332, 4099.569545197831, 4106.548279689291, 4107.240160570304, 4575.222212223864, 5007.273945277987, 5137.280780690781, 6169.660668663267, 6184.752609895554, 4885.3649025627865, 5571.0, 5926.585139040226, 5953.523718102628, 5960.092770313631, 4820.855374338776, 4992.8516441218, 5420.322459692538, 5849.239598852122, 5940.435658612425, 5514.448555144453, 5658.632188907708, 5785.218995435731, 5811.476967072984, 5926.140709597171, 4804.399450068741, 5908.261467316585, 5949.553783466344, 5950.851228719317, 5958.603595950267, 5478.767277362562, 5795.565332599987, 5819.883602327845, 5829.59192856496, 5830.484074140669], "value": 4302.753419322835, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [658.2167221007002, 722.9370935343371, 740.7370383513852, 741.4216910809564, 777.9144294127645, 14344.282785860705, 14360.066595671275, 14810.0, 14829.777553336695, 14848.1091134532, 13589.660258493543, 13654.52209172679, 13659.521916732916, 13710.588682339521, 13714.862851371483, 729.1760310848733, 856.5845564901018, 898.7797989492583, 931.3061768982118, 940.2102234123334, 900.4147304252256, 928.7213835849252, 929.0802105915163, 929.3958926697667, 933.9112784285516, 957.5403806173041, 965.9613615455373, 969.2585172343163, 972.9221662267037, 975.1029053270972, 933.6638810028452, 956.3783540698532, 966.3090890013639, 966.6954909203614, 969.3505351414569, 695.6556504530239, 707.961062141582, 716.5199316457986, 729.3508777188307, 730.5945200413768, 14352.425902963869, 14443.927780361031, 14455.27723613815, 14458.421663133568, 14464.349104290228, 10093.747656308651, 12405.44175512104, 12632.368381580976, 12632.873671263262, 12645.683857903534, 9816.558254878548, 9860.65487707934, 10026.799464010752, 10031.548580313904, 10122.64570740028, 3434.639362866888, 3448.3620530201742, 3477.0959550517045, 3506.4564992426212, 3665.3952097903816, 72511.63744181245, 75005.62497187534, 75468.62265688692, 75739.62130189368, 76548.61725691336, 7484.0, 9470.242380609534, 9481.24150067993, 9531.809363812685, 9635.855462168072, 21020.579588408145, 21092.156313747586, 21123.049462773906, 21170.682439763415, 21200.0, 7351.669174887143, 7362.558246505227, 7523.210062943422, 7536.0956685198125, 7598.582077985764, 4045.6358927696647, 4056.371262454324, 4086.3461846105074, 4104.322786740155, 4154.127633197002, 3435.3644575753565, 3563.625819288989, 3570.8571657133944, 3587.0494319005484, 3597.8560857565403, 574.9712514374264, 634.9365063493614, 643.8358218654213, 654.9214094308618, 658.8583454557294, 509.48796459557906, 541.8943306055281, 576.588337684946, 580.4398755201252, 598.6019297167422, 7930.484518506279, 8142.18578142181, 8265.0, 8265.545395003212, 8303.211194936472, 490.3894651159329, 529.0609168725543, 597.4503456819737, 607.1257389359308, 607.6475644126396, 3839.788811615388, 3944.467496887941, 3951.114679059174, 3983.123712783241, 4132.855350062793, 85025.0, 90101.64847527293, 99192.50403747935, 99698.50452243222, 101621.49189254154, 81783.0, 81904.0, 82097.0, 82272.58863705643, 83717.58141209377, 72039.55920881657, 72241.27758722345, 72386.63806680933, 72521.0, 72860.63569682224, 7747.573883436465, 7813.609319533887, 7854.293113619801, 7884.84230315379, 7895.447318687637, 40714.38928416017, 41675.79162104291, 42613.93465163272, 43997.0, 47008.0, 51170.97658046892, 54853.0, 60569.0914636272, 61138.69430652818, 62270.0, 9519.524023798644, 10980.670579882617, 11000.614978475714, 11170.720731981766, 11368.54525818958, 10296.845547316647, 11843.28940263587, 12054.698632534257, 12253.081018923438, 12515.311657858905, 11255.26840755381, 11326.546938122383, 11682.591109311134, 11707.2975621463, 11708.88291117112, 9612.903870961201, 11207.0, 11341.376224307742, 11351.432428378714, 11537.59618413385, 11048.723781905517, 11101.056410205223, 11313.0, 11516.136289778467, 11731.530738770354, 9447.952760236154, 9616.471094089893, 9658.468784216655, 9688.51557422112, 9795.06946840049, 9531.618735250513, 9812.362196457207, 9819.06718861707, 9975.551100200364, 10020.0], "value": 6804.326795516415, "unit": "score"}], "name": "dromaeo_css", "value": 7324.231142305721, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 117.0, 117.0, 119.0, 118.0, 132.0, 116.0, 118.0, 117.0, 114.0], "value": 118.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 136.0, 186.0, 178.0, 119.0, 136.0, 123.0, 152.0, 154.0, 125.0], "value": 146.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 192.0, 230.0, 261.0, 192.0, 196.0, 194.0, 199.0, 226.0, 275.0], "value": 216.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 80.0, 141.0, 55.0, 56.0, 57.0, 62.0, 125.0, 56.0], "value": 76.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 80.0, 134.0, 78.0, 78.0, 78.0, 116.0, 79.0, 82.0, 118.0], "value": 92.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 109.0, 86.0, 90.0, 86.0, 90.0, 86.0, 88.0, 86.0], "value": 89.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 111.0, 153.0, 95.0, 94.0, 95.0, 96.0, 117.0], "value": 104.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 115.0, 89.0, 110.0, 144.0, 111.0, 93.0, 89.0, 90.0, 92.0], "value": 107.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 66.0, 67.0, 64.0, 65.0, 66.0, 66.0, 79.0, 61.0, 59.0], "value": 65.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 65.0, 69.0, 74.0, 74.0, 69.0, 69.0, 67.0, 71.0], "value": 68.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 83.0, 83.0, 84.0, 83.0, 84.0, 83.0, 87.0, 104.0], "value": 85.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 163.0, 157.0, 135.0, 140.0, 158.0, 137.0, 136.0, 184.0, 161.0], "value": 152.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 184.0, 224.0, 177.0, 171.0, 170.0, 167.0, 169.0, 174.0, 168.0], "value": 177.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 63.0, 74.0, 70.0, 67.0, 70.0, 69.0, 69.0, 72.0, 72.0], "value": 69.3, "unit": "score"}], "name": "kraken", "value": 1570.2999999999997, "alertThreshold": 2.0}]} 07:41:45 INFO - Return code: 0 07:41:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 07:41:45 ERROR - # TBPL SUCCESS # 07:41:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:41:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:41:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:41:45 INFO - 2016-04-30 07:41:45.930 screenresolution[1689:11664] starting screenresolution argv=screenresolution get 07:41:45 INFO - 2016-04-30 07:41:45.932 screenresolution[1689:11664] Display 0: 1600x1200x32@60 07:41:45 INFO - 2016-04-30 07:41:45.938 screenresolution[1690:11667] starting screenresolution argv=screenresolution list 07:41:45 INFO - Available Modes on Display 0 07:41:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:41:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:41:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:41:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:41:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:41:46 INFO - Intel Iris: 07:41:46 INFO - Chipset Model: Intel Iris 07:41:46 INFO - Type: GPU 07:41:46 INFO - Bus: Built-In 07:41:46 INFO - VRAM (Dynamic, Max): 1536 MB 07:41:46 INFO - Vendor: Intel (0x8086) 07:41:46 INFO - Device ID: 0x0a2e 07:41:46 INFO - Revision ID: 0x0009 07:41:46 INFO - Displays: 07:41:46 INFO - Display: 07:41:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:41:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:41:46 INFO - Display Serial Number: 1600x1200 60 07:41:46 INFO - Main Display: Yes 07:41:46 INFO - Mirror: Off 07:41:46 INFO - Online: Yes 07:41:46 INFO - Rotation: Supported 07:41:46 INFO - Return code: 0 07:41:46 INFO - Running post-action listener: _resource_record_post_action 07:41:46 INFO - Running post-run listener: _resource_record_post_run 07:41:47 INFO - Total resource usage - Wall time: 1204s; CPU: 54.0%; Read bytes: 32235008; Write bytes: 656622080; Read time: 326; Write time: 1833 07:41:47 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 346792448; Write bytes: 347194880; Read time: 26673; Write time: 679 07:41:47 INFO - run-tests - Wall time: 1173s; CPU: 54.0%; Read bytes: 31309312; Write bytes: 299473920; Read time: 313; Write time: 1121 07:41:47 INFO - Running post-run listener: _upload_blobber_files 07:41:47 INFO - Blob upload gear active. 07:41:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:41:47 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 07:41:47 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'] 07:41:47 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 07:41:47 INFO - (blobuploader) - INFO - Open directory for files ... 07:41:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:41:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:41:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:41:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:41:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:41:48 INFO - (blobuploader) - INFO - Done attempting. 07:41:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:41:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:41:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:41:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:41:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:41:49 INFO - (blobuploader) - INFO - Done attempting. 07:41:49 INFO - (blobuploader) - INFO - Iteration through files over. 07:41:49 INFO - Return code: 0 07:41:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:41:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:41:49 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0063ef517e91c7db4376b0cf986cd3ea4e533d53ec9ada5288cd7a678505b30dd81845d3a6c2db66a68875e7d62c832e943b3b6c450a0f49cf4faa60d7f960e4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/675bcc085ffc058b03e9cd189e71c386b5efee00dd0fed82204e22b2e72454ae4f11784c54d0523a82298bfd1fd5db2da4844c8255ca0622005a114902d20315"} 07:41:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:41:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:41:49 INFO - Contents: 07:41:49 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0063ef517e91c7db4376b0cf986cd3ea4e533d53ec9ada5288cd7a678505b30dd81845d3a6c2db66a68875e7d62c832e943b3b6c450a0f49cf4faa60d7f960e4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/675bcc085ffc058b03e9cd189e71c386b5efee00dd0fed82204e22b2e72454ae4f11784c54d0523a82298bfd1fd5db2da4844c8255ca0622005a114902d20315"} 07:41:49 INFO - Running post-run listener: copy_logs_to_upload_dir 07:41:49 INFO - Copying logs to upload dir... 07:41:49 INFO - mkdir: /builds/slave/test/build/upload/logs 07:41:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1279.536504 ========= master_lag: 0.97 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2016-04-30 07:41:49.755986) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-30 07:41:49.760566) ========= 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.qWvtO6yxcT/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.vpEHavVyBJ/Listeners TMPDIR=/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/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/0063ef517e91c7db4376b0cf986cd3ea4e533d53ec9ada5288cd7a678505b30dd81845d3a6c2db66a68875e7d62c832e943b3b6c450a0f49cf4faa60d7f960e4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/675bcc085ffc058b03e9cd189e71c386b5efee00dd0fed82204e22b2e72454ae4f11784c54d0523a82298bfd1fd5db2da4844c8255ca0622005a114902d20315"} build_url:https://queue.taskcluster.net/v1/task/XbLHIlVfRManBcgwvhJmMw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011133 build_url: 'https://queue.taskcluster.net/v1/task/XbLHIlVfRManBcgwvhJmMw/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/0063ef517e91c7db4376b0cf986cd3ea4e533d53ec9ada5288cd7a678505b30dd81845d3a6c2db66a68875e7d62c832e943b3b6c450a0f49cf4faa60d7f960e4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/675bcc085ffc058b03e9cd189e71c386b5efee00dd0fed82204e22b2e72454ae4f11784c54d0523a82298bfd1fd5db2da4844c8255ca0622005a114902d20315"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-30 07:41:49.793946) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-30 07:41:49.794283) ========= 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.qWvtO6yxcT/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.vpEHavVyBJ/Listeners TMPDIR=/var/folders/8w/zj01xx051wq_syr3ttdymcvh00000w/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.005174 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-30 07:41:49.843018) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-30 07:41:49.843323) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-30 07:41:49.843678) ========= ========= Total master_lag: 31.26 =========