builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0273 starttime: 1463167656.77 results: success (0) buildid: 20160513103035 builduid: 6cd47609dea0442ebba730de9a3dd25f revision: ac470f026c69f90c82798f15dbf9d538fc99b68e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 12:27:36.766686) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 12:27:36.767108) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-13 12:27:36.767390) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-13 12:27:37.606895) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 12:27:37.607196) ========= 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.VzFHyujtAv/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.lkbOIc2dZ2/Listeners TMPDIR=/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-13 12:27:37-- 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% 11.7M=0.001s 2016-05-13 12:27:37 (11.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.148038 ========= master_lag: 0.21 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 12:27:37.960276) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 12:27:37.960698) ========= 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.VzFHyujtAv/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.lkbOIc2dZ2/Listeners TMPDIR=/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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.024934 ========= master_lag: 0.11 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 12:27:38.097695) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-13 12:27:38.098752) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ac470f026c69f90c82798f15dbf9d538fc99b68e --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 ac470f026c69f90c82798f15dbf9d538fc99b68e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.VzFHyujtAv/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.lkbOIc2dZ2/Listeners TMPDIR=/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-13 12:27:38,201 truncating revision to first 12 chars 2016-05-13 12:27:38,201 Setting DEBUG logging. 2016-05-13 12:27:38,201 attempt 1/10 2016-05-13 12:27:38,201 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/ac470f026c69?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-13 12:27:39,184 unpacking tar archive at: mozilla-aurora-ac470f026c69/testing/mozharness/ program finished with exit code 0 elapsedTime=1.241613 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-13 12:27:39.385118) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 12:27:39.385457) ========= script_repo_revision: ac470f026c69f90c82798f15dbf9d538fc99b68e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 12:27:39.388329) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 12:27:39.388667) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 12:27:39.407814) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 6 secs) (at 2016-05-13 12:27:39.408255) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.VzFHyujtAv/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.lkbOIc2dZ2/Listeners TMPDIR=/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 12:27:39 INFO - MultiFileLogger online at 20160513 12:27:39 in /builds/slave/test 12:27:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 12:27:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:27:39 INFO - {'append_to_log': False, 12:27:39 INFO - 'base_work_dir': '/builds/slave/test', 12:27:39 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 12:27:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:27:39 INFO - 'branch': 'Mozilla-Aurora', 12:27:39 INFO - 'buildbot_json_path': 'buildprops.json', 12:27:39 INFO - 'config_files': ('talos/mac_config.py',), 12:27:39 INFO - 'default_actions': ('clobber', 12:27:39 INFO - 'read-buildbot-config', 12:27:39 INFO - 'download-and-extract', 12:27:39 INFO - 'populate-webroot', 12:27:39 INFO - 'create-virtualenv', 12:27:39 INFO - 'install', 12:27:39 INFO - 'run-tests'), 12:27:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:27:39 INFO - 'download_minidump_stackwalk': True, 12:27:39 INFO - 'download_symbols': 'ondemand', 12:27:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:27:39 INFO - 'tooltool.py': '/tools/tooltool.py', 12:27:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:27:39 INFO - '/tools/misc-python/virtualenv.py')}, 12:27:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:27:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:27:39 INFO - 'installer_path': 'installer.exe', 12:27:39 INFO - 'log_level': 'info', 12:27:39 INFO - 'log_name': 'talos', 12:27:39 INFO - 'log_to_console': True, 12:27:39 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:27:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:27:39 INFO - 'opt_config_files': (), 12:27:39 INFO - 'pip_index': False, 12:27:39 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:27:39 INFO - 'cmd': ('bash', 12:27:39 INFO - '-c', 12:27:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:27:39 INFO - 'enabled': True, 12:27:39 INFO - 'halt_on_failure': False, 12:27:39 INFO - 'name': 'check_screen_resolution'},), 12:27:39 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:27:39 INFO - 'cmd': ('bash', 12:27:39 INFO - '-c', 12:27:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:27:39 INFO - 'enabled': True, 12:27:39 INFO - 'halt_on_failure': False, 12:27:39 INFO - 'name': 'check_screen_resolution'},), 12:27:39 INFO - 'run_cmd_checks_enabled': True, 12:27:39 INFO - 'sps_profile': False, 12:27:39 INFO - 'sps_profile_interval': 0, 12:27:39 INFO - 'suite': 'dromaeojs', 12:27:39 INFO - 'system_bits': '32', 12:27:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:27:39 INFO - 'title': 't-yosemite-r7-0273', 12:27:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:27:39 INFO - 'use_talos_json': True, 12:27:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:27:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:27:39 INFO - 'work_dir': 'build'} 12:27:39 INFO - ##### 12:27:39 INFO - ##### Running clobber step. 12:27:39 INFO - ##### 12:27:39 INFO - Running pre-action listener: _resource_record_pre_action 12:27:39 INFO - Running main action method: clobber 12:27:39 INFO - rmtree: /builds/slave/test/build 12:27:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:27:41 INFO - Running post-action listener: _resource_record_post_action 12:27:41 INFO - ##### 12:27:41 INFO - ##### Running read-buildbot-config step. 12:27:41 INFO - ##### 12:27:41 INFO - Running pre-action listener: _resource_record_pre_action 12:27:41 INFO - Running main action method: read_buildbot_config 12:27:41 INFO - Using buildbot properties: 12:27:41 INFO - { 12:27:41 INFO - "project": "", 12:27:41 INFO - "product": "firefox", 12:27:41 INFO - "script_repo_revision": "production", 12:27:41 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 12:27:41 INFO - "repository": "", 12:27:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 12:27:41 INFO - "buildid": "20160513103035", 12:27:41 INFO - "pgo_build": "False", 12:27:41 INFO - "basedir": "/builds/slave/test", 12:27:41 INFO - "buildnumber": 6, 12:27:41 INFO - "slavename": "t-yosemite-r7-0273", 12:27:41 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 12:27:41 INFO - "platform": "yosemite_r7", 12:27:41 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 12:27:41 INFO - "branch": "mozilla-aurora", 12:27:41 INFO - "repo_path": "releases/mozilla-aurora", 12:27:41 INFO - "revision": "ac470f026c69f90c82798f15dbf9d538fc99b68e", 12:27:41 INFO - "stage_platform": "macosx64", 12:27:41 INFO - "builduid": "6cd47609dea0442ebba730de9a3dd25f", 12:27:41 INFO - "slavebuilddir": "test" 12:27:41 INFO - } 12:27:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/AT41oYU2Te-vURtmU60Fxw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 12:27:41 INFO - Running post-action listener: _resource_record_post_action 12:27:41 INFO - ##### 12:27:41 INFO - ##### Running download-and-extract step. 12:27:41 INFO - ##### 12:27:41 INFO - Running pre-action listener: _resource_record_pre_action 12:27:41 INFO - Running main action method: download_and_extract 12:27:41 INFO - mkdir: /builds/slave/test/build/tests 12:27:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:27:41 INFO - https://queue.taskcluster.net/v1/task/AT41oYU2Te-vURtmU60Fxw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 12:27:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AT41oYU2Te-vURtmU60Fxw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 12:27:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AT41oYU2Te-vURtmU60Fxw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 12:27:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AT41oYU2Te-vURtmU60Fxw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 12:27:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AT41oYU2Te-vURtmU60Fxw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 12:28:11 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AT41oYU2Te-vURtmU60Fxw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json: timed out 12:28:11 INFO - retry: attempt #1 caught exception: timed out 12:28:11 INFO - retry: Failed, sleeping 30 seconds before retrying 12:28:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AT41oYU2Te-vURtmU60Fxw/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 #2 12:28:43 INFO - Downloaded 1199 bytes. 12:28:43 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 12:28:43 INFO - Using the following test package requirements: 12:28:43 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 12:28:43 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:28:43 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 12:28:43 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:28:43 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 12:28:43 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:28:43 INFO - u'jsshell-mac.zip'], 12:28:43 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:28:43 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 12:28:43 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 12:28:43 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:28:43 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 12:28:43 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:28:43 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 12:28:43 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:28:43 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 12:28:43 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:28:43 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 12:28:43 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 12:28:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:28:43 INFO - https://queue.taskcluster.net/v1/task/AT41oYU2Te-vURtmU60Fxw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:28:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AT41oYU2Te-vURtmU60Fxw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 12:28:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AT41oYU2Te-vURtmU60Fxw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 12:28:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AT41oYU2Te-vURtmU60Fxw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 12:28:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AT41oYU2Te-vURtmU60Fxw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 12:28:45 INFO - Downloaded 18089500 bytes. 12:28:45 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:28:45 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 12:28:48 INFO - Return code: 0 12:28:48 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 12:28:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:28:48 INFO - https://queue.taskcluster.net/v1/task/AT41oYU2Te-vURtmU60Fxw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:28:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AT41oYU2Te-vURtmU60Fxw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 12:28:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AT41oYU2Te-vURtmU60Fxw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 12:28:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AT41oYU2Te-vURtmU60Fxw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 12:28:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AT41oYU2Te-vURtmU60Fxw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 12:28:49 INFO - Downloaded 18089500 bytes. 12:28:49 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:28:49 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 12:28:52 INFO - Return code: 0 12:28:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:28:52 INFO - https://queue.taskcluster.net/v1/task/AT41oYU2Te-vURtmU60Fxw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 12:28:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AT41oYU2Te-vURtmU60Fxw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 12:28:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AT41oYU2Te-vURtmU60Fxw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 12:28:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AT41oYU2Te-vURtmU60Fxw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 12:28:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AT41oYU2Te-vURtmU60Fxw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 12:28:53 INFO - Downloaded 10840145 bytes. 12:28:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:28:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 12:28:53 INFO - Return code: 0 12:28:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:28:53 INFO - https://queue.taskcluster.net/v1/task/AT41oYU2Te-vURtmU60Fxw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 12:28:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AT41oYU2Te-vURtmU60Fxw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 12:28:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AT41oYU2Te-vURtmU60Fxw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 12:28:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AT41oYU2Te-vURtmU60Fxw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 12:28:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AT41oYU2Te-vURtmU60Fxw/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 12:28:58 INFO - Downloaded 114123945 bytes. 12:28:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AT41oYU2Te-vURtmU60Fxw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 12:28:58 INFO - mkdir: /builds/slave/test/properties 12:28:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:28:58 INFO - Writing to file /builds/slave/test/properties/build_url 12:28:58 INFO - Contents: 12:28:58 INFO - build_url:https://queue.taskcluster.net/v1/task/AT41oYU2Te-vURtmU60Fxw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 12:28:59 INFO - Running post-action listener: _resource_record_post_action 12:28:59 INFO - Running post-action listener: set_extra_try_arguments 12:28:59 INFO - ##### 12:28:59 INFO - ##### Running populate-webroot step. 12:28:59 INFO - ##### 12:28:59 INFO - Running pre-action listener: _resource_record_pre_action 12:28:59 INFO - Running main action method: populate_webroot 12:28:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:28:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:28:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:28:59 INFO - '--tppagecycles', 12:28:59 INFO - '7'], 12:28:59 INFO - 'tests': ['tsvgm']}}, 12:28:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:28:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:28:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:28:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:28:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:28:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:28:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:28:59 INFO - 'talos_options': ['--disable-e10s'], 12:28:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:28:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:28:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:28:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:28:59 INFO - 'talos_options': ['--disable-e10s'], 12:28:59 INFO - 'tests': ['damp', 'tps']}, 12:28:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:28:59 INFO - 'tests': ['damp', 'tps']}, 12:28:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:28:59 INFO - 'tests': ['dromaeo_dom']}, 12:28:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:28:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:28:59 INFO - 'tests': ['a11yr', 12:28:59 INFO - 'ts_paint', 12:28:59 INFO - 'tpaint', 12:28:59 INFO - 'sessionrestore', 12:28:59 INFO - 'sessionrestore_no_auto_restore', 12:28:59 INFO - 'tabpaint']}, 12:28:59 INFO - 'other-e10s': {'tests': ['a11yr', 12:28:59 INFO - 'ts_paint', 12:28:59 INFO - 'tpaint', 12:28:59 INFO - 'sessionrestore', 12:28:59 INFO - 'sessionrestore_no_auto_restore', 12:28:59 INFO - 'tabpaint']}, 12:28:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:28:59 INFO - 'ts_paint', 12:28:59 INFO - 'tpaint', 12:28:59 INFO - 'sessionrestore', 12:28:59 INFO - 'sessionrestore_no_auto_restore', 12:28:59 INFO - 'tabpaint']}, 12:28:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:28:59 INFO - 'ts_paint', 12:28:59 INFO - 'tpaint', 12:28:59 INFO - 'sessionrestore', 12:28:59 INFO - 'sessionrestore_no_auto_restore', 12:28:59 INFO - 'tabpaint']}, 12:28:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:28:59 INFO - 'tests': ['a11yr', 12:28:59 INFO - 'ts_paint', 12:28:59 INFO - 'tpaint', 12:28:59 INFO - 'sessionrestore', 12:28:59 INFO - 'sessionrestore_no_auto_restore', 12:28:59 INFO - 'tabpaint']}, 12:28:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:28:59 INFO - 'tests': ['a11yr', 12:28:59 INFO - 'ts_paint', 12:28:59 INFO - 'tpaint', 12:28:59 INFO - 'sessionrestore', 12:28:59 INFO - 'sessionrestore_no_auto_restore', 12:28:59 INFO - 'tabpaint']}, 12:28:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:28:59 INFO - 'tests': ['tsvgx', 12:28:59 INFO - 'tsvgr_opacity', 12:28:59 INFO - 'tart', 12:28:59 INFO - 'tscrollx', 12:28:59 INFO - 'cart']}, 12:28:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:28:59 INFO - 'tsvgr_opacity', 12:28:59 INFO - 'tart', 12:28:59 INFO - 'tscrollx', 12:28:59 INFO - 'cart']}, 12:28:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:28:59 INFO - 'talos_options': ['--disable-e10s'], 12:28:59 INFO - 'tests': ['tp5o']}, 12:28:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:28:59 INFO - 'tests': ['tp5o']}, 12:28:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:28:59 INFO - 'talos_options': ['--disable-e10s', 12:28:59 INFO - '--xperf_path', 12:28:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:28:59 INFO - 'tests': ['tp5n']}, 12:28:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:28:59 INFO - 'talos_options': ['--xperf_path', 12:28:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:28:59 INFO - 'tests': ['tp5n']}}, 12:28:59 INFO - 'talos.zip': {'path': '', 12:28:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:28:59 INFO - Running post-action listener: _resource_record_post_action 12:28:59 INFO - ##### 12:28:59 INFO - ##### Running create-virtualenv step. 12:28:59 INFO - ##### 12:28:59 INFO - Running pre-action listener: _resource_record_pre_action 12:28:59 INFO - Running main action method: create_virtualenv 12:28:59 INFO - Creating virtualenv /builds/slave/test/build/venv 12:28:59 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 12:28:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:28:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:28:59 INFO - Using real prefix '/tools/python27' 12:28:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:29:00 INFO - Installing distribute.............................................................................................................................................................................................done. 12:29:02 INFO - Installing pip.................done. 12:29:02 INFO - Return code: 0 12:29:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:29:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:29:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:29:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:02 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 0x10549aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1054aa420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb471d48190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105339e00>, '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 0x1051a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105334b20>, '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.VzFHyujtAv/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.lkbOIc2dZ2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 12:29:02 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 12:29:02 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 12:29:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VzFHyujtAv/Render', 12:29:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:29:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:29:02 INFO - 'HOME': '/Users/cltbld', 12:29:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:29:02 INFO - 'LOGNAME': 'cltbld', 12:29:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:02 INFO - 'MOZ_NO_REMOTE': '1', 12:29:02 INFO - 'NO_EM_RESTART': '1', 12:29:02 INFO - 'PAGER': '/bin/cat', 12:29:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:29:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:02 INFO - 'PWD': '/builds/slave/test', 12:29:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:29:02 INFO - 'SHELL': '/bin/bash', 12:29:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lkbOIc2dZ2/Listeners', 12:29:02 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 12:29:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:29:02 INFO - 'USER': 'cltbld', 12:29:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:29:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:29:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:02 INFO - 'XPC_FLAGS': '0x0', 12:29:02 INFO - 'XPC_SERVICE_NAME': '0', 12:29:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:29:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:03 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 12:29:03 INFO - Cleaning up... 12:29:03 INFO - Return code: 0 12:29:03 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 12:29:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:29:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:29:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:03 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 0x10549aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1054aa420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb471d48190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105339e00>, '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 0x1051a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105334b20>, '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.VzFHyujtAv/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.lkbOIc2dZ2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 12:29:03 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 12:29:03 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 12:29:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VzFHyujtAv/Render', 12:29:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:29:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:29:03 INFO - 'HOME': '/Users/cltbld', 12:29:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:29:03 INFO - 'LOGNAME': 'cltbld', 12:29:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:03 INFO - 'MOZ_NO_REMOTE': '1', 12:29:03 INFO - 'NO_EM_RESTART': '1', 12:29:03 INFO - 'PAGER': '/bin/cat', 12:29:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:29:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:03 INFO - 'PWD': '/builds/slave/test', 12:29:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:29:03 INFO - 'SHELL': '/bin/bash', 12:29:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lkbOIc2dZ2/Listeners', 12:29:03 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 12:29:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:29:03 INFO - 'USER': 'cltbld', 12:29:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:29:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:29:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:03 INFO - 'XPC_FLAGS': '0x0', 12:29:03 INFO - 'XPC_SERVICE_NAME': '0', 12:29:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:29:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:03 INFO - Downloading/unpacking psutil>=0.7.1 12:29:03 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 12:29:03 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 12:29:03 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 12:29:03 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 12:29:03 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 12:29:03 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 12:29:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:29:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:29:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:29:06 INFO - Installing collected packages: psutil 12:29:06 INFO - Running setup.py install for psutil 12:29:06 INFO - building 'psutil._psutil_osx' extension 12:29:06 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 12:29:07 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 12:29:07 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 12:29:07 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 12:29:07 INFO - building 'psutil._psutil_posix' extension 12:29:07 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 12:29:07 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:29:07 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:29:07 INFO - ^ 12:29:07 INFO - 1 warning generated. 12:29:07 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 12:29:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:29:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:29:07 INFO - Successfully installed psutil 12:29:07 INFO - Cleaning up... 12:29:07 INFO - Return code: 0 12:29:07 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 12:29:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:29:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:29:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:07 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 0x10549aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1054aa420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb471d48190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105339e00>, '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 0x1051a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105334b20>, '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.VzFHyujtAv/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.lkbOIc2dZ2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 12:29:07 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 12:29:07 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 12:29:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VzFHyujtAv/Render', 12:29:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:29:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:29:07 INFO - 'HOME': '/Users/cltbld', 12:29:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:29:07 INFO - 'LOGNAME': 'cltbld', 12:29:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:07 INFO - 'MOZ_NO_REMOTE': '1', 12:29:07 INFO - 'NO_EM_RESTART': '1', 12:29:07 INFO - 'PAGER': '/bin/cat', 12:29:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:29:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:07 INFO - 'PWD': '/builds/slave/test', 12:29:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:29:07 INFO - 'SHELL': '/bin/bash', 12:29:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lkbOIc2dZ2/Listeners', 12:29:07 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 12:29:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:29:07 INFO - 'USER': 'cltbld', 12:29:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:29:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:29:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:07 INFO - 'XPC_FLAGS': '0x0', 12:29:07 INFO - 'XPC_SERVICE_NAME': '0', 12:29:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:29:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:08 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 12:29:08 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 12:29:08 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 12:29:08 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 12:29:08 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 12:29:08 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 12:29:08 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 12:29:10 INFO - Downloading mozsystemmonitor-0.0.tar.gz 12:29:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:29:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 12:29:10 INFO - Installing collected packages: mozsystemmonitor 12:29:10 INFO - Running setup.py install for mozsystemmonitor 12:29:10 INFO - Successfully installed mozsystemmonitor 12:29:10 INFO - Cleaning up... 12:29:10 INFO - Return code: 0 12:29:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:29: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')]} 12:29:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29: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')]} 12:29:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29: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', '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 0x10549aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1054aa420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb471d48190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105339e00>, '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 0x1051a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105334b20>, '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.VzFHyujtAv/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.lkbOIc2dZ2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 12:29: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:29: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 blobuploader==1.2.4 12:29:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VzFHyujtAv/Render', 12:29:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:29:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:29:10 INFO - 'HOME': '/Users/cltbld', 12:29:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:29:10 INFO - 'LOGNAME': 'cltbld', 12:29:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:10 INFO - 'MOZ_NO_REMOTE': '1', 12:29:10 INFO - 'NO_EM_RESTART': '1', 12:29:10 INFO - 'PAGER': '/bin/cat', 12:29:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:29:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:10 INFO - 'PWD': '/builds/slave/test', 12:29:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:29:10 INFO - 'SHELL': '/bin/bash', 12:29:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lkbOIc2dZ2/Listeners', 12:29:10 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 12:29:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:29:10 INFO - 'USER': 'cltbld', 12:29:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:29:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:29:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:10 INFO - 'XPC_FLAGS': '0x0', 12:29:10 INFO - 'XPC_SERVICE_NAME': '0', 12:29:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:29:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:11 INFO - Downloading/unpacking blobuploader==1.2.4 12:29: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 12:29: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 12:29: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 12:29: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 12:29: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 12:29: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 12:29:13 INFO - Downloading blobuploader-1.2.4.tar.gz 12:29:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:29:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:29:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:29:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:29:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:29:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:29:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:29:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:29:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:29:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:29:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:29:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:29:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:29:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:29:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:29:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:29:14 INFO - Downloading docopt-0.6.1.tar.gz 12:29:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:29:14 INFO - Installing collected packages: blobuploader, requests, docopt 12:29:14 INFO - Running setup.py install for blobuploader 12:29:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:29:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:29:15 INFO - Running setup.py install for requests 12:29:15 INFO - Running setup.py install for docopt 12:29:15 INFO - Successfully installed blobuploader requests docopt 12:29:15 INFO - Cleaning up... 12:29:15 INFO - Return code: 0 12:29:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:29: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')]} 12:29:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29: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')]} 12:29:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:15 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 0x10549aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1054aa420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb471d48190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105339e00>, '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 0x1051a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105334b20>, '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.VzFHyujtAv/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.lkbOIc2dZ2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 12:29:15 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 12:29:15 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 12:29:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VzFHyujtAv/Render', 12:29:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:29:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:29:15 INFO - 'HOME': '/Users/cltbld', 12:29:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:29:15 INFO - 'LOGNAME': 'cltbld', 12:29:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:15 INFO - 'MOZ_NO_REMOTE': '1', 12:29:15 INFO - 'NO_EM_RESTART': '1', 12:29:15 INFO - 'PAGER': '/bin/cat', 12:29:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:29:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:15 INFO - 'PWD': '/builds/slave/test', 12:29:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:29:15 INFO - 'SHELL': '/bin/bash', 12:29:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lkbOIc2dZ2/Listeners', 12:29:15 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 12:29:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:29:15 INFO - 'USER': 'cltbld', 12:29:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:29:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:29:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:15 INFO - 'XPC_FLAGS': '0x0', 12:29:15 INFO - 'XPC_SERVICE_NAME': '0', 12:29:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:29:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:29:16 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-Qrk7iK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:29:16 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-c3MvuM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:29:16 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-VsKMMT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:29:16 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-r0WExm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:29:16 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-t8uPZG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:29:16 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-iX5nuV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:29:16 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-joac3T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:29:16 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-eddzhZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:29:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:29:16 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-tlHEfc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:29:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:29:17 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-241tB1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:29:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:29:17 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-aM6wBt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:29:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:29:17 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-QjsFWy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:29:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:29:17 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-ehFKlk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:29:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:29:17 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-XGf4lx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:29:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:29:17 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-5sz0zg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:29:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:29:17 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-2Cktus-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:29:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:29:17 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-XJIdX5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:29:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:29:17 INFO - Running setup.py install for manifestparser 12:29:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:29:17 INFO - Running setup.py install for mozcrash 12:29:18 INFO - Running setup.py install for mozdebug 12:29:18 INFO - Running setup.py install for mozdevice 12:29:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:29:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:29:18 INFO - Running setup.py install for mozfile 12:29:18 INFO - Running setup.py install for mozhttpd 12:29:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:29:18 INFO - Running setup.py install for mozinfo 12:29:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:29:18 INFO - Running setup.py install for mozInstall 12:29:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:29:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:29:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:29:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:29:18 INFO - Running setup.py install for mozleak 12:29:19 INFO - Running setup.py install for mozlog 12:29:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:29:19 INFO - Running setup.py install for moznetwork 12:29:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:29:19 INFO - Running setup.py install for mozprocess 12:29:19 INFO - Running setup.py install for mozprofile 12:29:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:29:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:29:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:29:19 INFO - Running setup.py install for mozrunner 12:29:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:29:19 INFO - Running setup.py install for mozscreenshot 12:29:20 INFO - Running setup.py install for moztest 12:29:20 INFO - Running setup.py install for mozversion 12:29:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:29:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:29:20 INFO - Cleaning up... 12:29:20 INFO - Return code: 0 12:29:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:29:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:29:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:29:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:20 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 0x10549aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1054aa420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb471d48190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105339e00>, '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 0x1051a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105334b20>, '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.VzFHyujtAv/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.lkbOIc2dZ2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 12:29:20 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 12:29:20 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 12:29:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VzFHyujtAv/Render', 12:29:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:29:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:29:20 INFO - 'HOME': '/Users/cltbld', 12:29:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:29:20 INFO - 'LOGNAME': 'cltbld', 12:29:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:20 INFO - 'MOZ_NO_REMOTE': '1', 12:29:20 INFO - 'NO_EM_RESTART': '1', 12:29:20 INFO - 'PAGER': '/bin/cat', 12:29:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:29:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:20 INFO - 'PWD': '/builds/slave/test', 12:29:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:29:20 INFO - 'SHELL': '/bin/bash', 12:29:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lkbOIc2dZ2/Listeners', 12:29:20 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 12:29:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:29:20 INFO - 'USER': 'cltbld', 12:29:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:29:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:29:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:20 INFO - 'XPC_FLAGS': '0x0', 12:29:20 INFO - 'XPC_SERVICE_NAME': '0', 12:29:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:29:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:29:20 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-1r7XDQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:29:20 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)) 12:29:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:29:20 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-fyfLAM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:29:20 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)) 12:29:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:29:20 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-IjLZyY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:29:21 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)) 12:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:29:21 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-jGeIQR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:29:21 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)) 12:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:29:21 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-fO7ISx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:29:21 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)) 12:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:29:21 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-IloiQs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:29:21 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)) 12:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:29:21 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-tow2FB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:29:21 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)) 12:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:29:21 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-33ijIf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:29:21 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)) 12:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:29:21 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-8A8w6c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:29:21 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)) 12:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:29:21 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-xM7Vpx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:29:21 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)) 12:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:29:21 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-lv1fHj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:29:21 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)) 12:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:29:21 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-LNO9w4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:29:21 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)) 12:29:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:29:21 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-_FVbz9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:29:22 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)) 12:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:29:22 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-N0lm8s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:29:22 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)) 12:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:29:22 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-yup_Ep-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:29:22 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)) 12:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:29:22 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-ds0PBy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:29:22 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)) 12:29:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:29:22 INFO - Running setup.py (path:/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/pip-PsYqKS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:29:22 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)) 12:29:22 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)) 12:29:22 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)) 12:29:22 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)) 12:29:22 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)) 12:29:22 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)) 12:29:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:29:22 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 12:29:22 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 12:29:22 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 12:29:22 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 12:29:22 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 12:29:22 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 12:29:24 INFO - Downloading blessings-1.6.tar.gz 12:29:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:29:25 INFO - Installing collected packages: blessings 12:29:25 INFO - Running setup.py install for blessings 12:29:25 INFO - Successfully installed blessings 12:29:25 INFO - Cleaning up... 12:29:25 INFO - Return code: 0 12:29:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:29:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:29:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:29:25 INFO - Reading from file tmpfile_stdout 12:29:25 INFO - Current package versions: 12:29:25 INFO - blessings == 1.6 12:29:25 INFO - blobuploader == 1.2.4 12:29:25 INFO - docopt == 0.6.1 12:29:25 INFO - manifestparser == 1.1 12:29:25 INFO - mozInstall == 1.12 12:29:25 INFO - mozcrash == 0.17 12:29:25 INFO - mozdebug == 0.1 12:29:25 INFO - mozdevice == 0.48 12:29:25 INFO - mozfile == 1.2 12:29:25 INFO - mozhttpd == 0.7 12:29:25 INFO - mozinfo == 0.9 12:29:25 INFO - mozleak == 0.1 12:29:25 INFO - mozlog == 3.1 12:29:25 INFO - moznetwork == 0.27 12:29:25 INFO - mozprocess == 0.22 12:29:25 INFO - mozprofile == 0.28 12:29:25 INFO - mozrunner == 6.11 12:29:25 INFO - mozscreenshot == 0.1 12:29:25 INFO - mozsystemmonitor == 0.0 12:29:25 INFO - moztest == 0.7 12:29:25 INFO - mozversion == 1.4 12:29:25 INFO - psutil == 3.1.1 12:29:25 INFO - requests == 1.2.3 12:29:25 INFO - wsgiref == 0.1.2 12:29:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:29:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:29:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:29:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:25 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 0x10549aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1054aa420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb471d48190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105339e00>, '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 0x1051a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105334b20>, '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.VzFHyujtAv/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.lkbOIc2dZ2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 12:29:25 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 12:29:25 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 12:29:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VzFHyujtAv/Render', 12:29:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:29:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:29:25 INFO - 'HOME': '/Users/cltbld', 12:29:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:29:25 INFO - 'LOGNAME': 'cltbld', 12:29:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:25 INFO - 'MOZ_NO_REMOTE': '1', 12:29:25 INFO - 'NO_EM_RESTART': '1', 12:29:25 INFO - 'PAGER': '/bin/cat', 12:29:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:29:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:25 INFO - 'PWD': '/builds/slave/test', 12:29:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:29:25 INFO - 'SHELL': '/bin/bash', 12:29:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lkbOIc2dZ2/Listeners', 12:29:25 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 12:29:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:29:25 INFO - 'USER': 'cltbld', 12:29:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:29:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:29:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:25 INFO - 'XPC_FLAGS': '0x0', 12:29:25 INFO - 'XPC_SERVICE_NAME': '0', 12:29:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:29:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:25 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)) 12:29:25 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)) 12:29:25 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)) 12:29:25 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)) 12:29:25 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)) 12:29:25 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)) 12:29:25 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)) 12:29:25 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)) 12:29:25 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)) 12:29:25 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)) 12:29:25 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)) 12:29:25 INFO - Cleaning up... 12:29:25 INFO - Return code: 0 12:29:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:29:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:29:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:29:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:25 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 0x10549aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1054aa420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb471d48190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105339e00>, '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 0x1051a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105334b20>, '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.VzFHyujtAv/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.lkbOIc2dZ2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 12:29:25 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 12:29:25 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 12:29:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VzFHyujtAv/Render', 12:29:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:29:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:29:25 INFO - 'HOME': '/Users/cltbld', 12:29:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:29:25 INFO - 'LOGNAME': 'cltbld', 12:29:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:25 INFO - 'MOZ_NO_REMOTE': '1', 12:29:25 INFO - 'NO_EM_RESTART': '1', 12:29:25 INFO - 'PAGER': '/bin/cat', 12:29:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:29:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:25 INFO - 'PWD': '/builds/slave/test', 12:29:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:29:25 INFO - 'SHELL': '/bin/bash', 12:29:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lkbOIc2dZ2/Listeners', 12:29:25 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 12:29:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:29:25 INFO - 'USER': 'cltbld', 12:29:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:29:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:29:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:25 INFO - 'XPC_FLAGS': '0x0', 12:29:25 INFO - 'XPC_SERVICE_NAME': '0', 12:29:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:29:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:26 INFO - Downloading/unpacking jsonschema 12:29:26 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 12:29:26 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 12:29:26 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 12:29:26 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 12:29:26 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 12:29:26 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 12:29:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:29:28 INFO - Downloading/unpacking functools32 (from jsonschema) 12:29:28 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 12:29:28 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 12:29:28 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 12:29:28 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 12:29:28 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 12:29:28 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 12:29:28 INFO - Downloading functools32-3.2.3-2.tar.gz 12:29:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:29:28 INFO - warning: no files found matching '*.txt' 12:29:28 INFO - no previously-included directories found matching 'build' 12:29:28 INFO - no previously-included directories found matching 'dist' 12:29:28 INFO - no previously-included directories found matching '.git*' 12:29:29 INFO - Installing collected packages: jsonschema, functools32 12:29:29 INFO - Running setup.py install for functools32 12:29:29 INFO - warning: no files found matching '*.txt' 12:29:29 INFO - no previously-included directories found matching 'build' 12:29:29 INFO - no previously-included directories found matching 'dist' 12:29:29 INFO - no previously-included directories found matching '.git*' 12:29:29 INFO - Successfully installed jsonschema functools32 12:29:29 INFO - Cleaning up... 12:29:29 INFO - Return code: 0 12:29:29 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 12:29:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:29:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:29:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:29:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:29:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:29:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10549aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1054aa420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb471d48190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105339e00>, '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 0x1051a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105334b20>, '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.VzFHyujtAv/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.lkbOIc2dZ2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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 12:29:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 12:29:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 12:29:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VzFHyujtAv/Render', 12:29:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:29:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:29:29 INFO - 'HOME': '/Users/cltbld', 12:29:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:29:29 INFO - 'LOGNAME': 'cltbld', 12:29:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:29:29 INFO - 'MOZ_NO_REMOTE': '1', 12:29:29 INFO - 'NO_EM_RESTART': '1', 12:29:29 INFO - 'PAGER': '/bin/cat', 12:29:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:29:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:29:29 INFO - 'PWD': '/builds/slave/test', 12:29:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:29:29 INFO - 'SHELL': '/bin/bash', 12:29:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lkbOIc2dZ2/Listeners', 12:29:29 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 12:29:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:29:29 INFO - 'USER': 'cltbld', 12:29:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:29:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:29:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:29:29 INFO - 'XPC_FLAGS': '0x0', 12:29:29 INFO - 'XPC_SERVICE_NAME': '0', 12:29:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:29:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:29:29 INFO - Downloading/unpacking flake8 12:29: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 12:29: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 12:29: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 12:29: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 12:29: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 12:29: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 12:29:31 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 12:29:31 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 12:29:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:29:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:29:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:29:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:29:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:29:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:29:32 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 12:29:32 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 12:29:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:29:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:29:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:29:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:29:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:29:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:29:32 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 12:29:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:29:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:29:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:29:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:29:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:29:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:29:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 12:29:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:29:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:29:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:29:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:29:33 INFO - no previously-included directories found matching 'docs/_build' 12:29:33 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 12:29:33 INFO - Running setup.py install for pep8 12:29:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:29:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:29:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:29:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:29:33 INFO - no previously-included directories found matching 'docs/_build' 12:29:33 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 12:29:33 INFO - Successfully installed flake8 mccabe pyflakes pep8 12:29:33 INFO - Cleaning up... 12:29:33 INFO - Return code: 0 12:29:33 INFO - Running post-action listener: _resource_record_post_action 12:29:33 INFO - Running post-action listener: _start_resource_monitoring 12:29:33 INFO - Starting resource monitoring. 12:29:33 INFO - ##### 12:29:33 INFO - ##### Running install step. 12:29:33 INFO - ##### 12:29:33 INFO - Running pre-action listener: _resource_record_pre_action 12:29:33 INFO - Running main action method: install 12:29:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:29:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:29:33 INFO - Reading from file tmpfile_stdout 12:29:33 INFO - Detecting whether we're running mozinstall >=1.0... 12:29:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:29:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:29:34 INFO - Reading from file tmpfile_stdout 12:29:34 INFO - Output received: 12:29:34 INFO - Usage: mozinstall [options] installer 12:29:34 INFO - Options: 12:29:34 INFO - -h, --help show this help message and exit 12:29:34 INFO - -d DEST, --destination=DEST 12:29:34 INFO - Directory to install application into. [default: 12:29:34 INFO - "/builds/slave/test"] 12:29:34 INFO - --app=APP Application being installed. [default: firefox] 12:29:34 INFO - mkdir: /builds/slave/test/build/application 12:29:34 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'] 12:29:34 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 12:30:05 INFO - Reading from file tmpfile_stdout 12:30:05 INFO - Output received: 12:30:05 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 12:30:05 INFO - Running post-action listener: _resource_record_post_action 12:30:05 INFO - ##### 12:30:05 INFO - ##### Running run-tests step. 12:30:05 INFO - ##### 12:30:05 INFO - Running pre-action listener: _resource_record_pre_action 12:30:05 INFO - Running main action method: run_tests 12:30:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:30:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:30:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:30:05 INFO - 2016-05-13 12:30:05.484 screenresolution[1645:7927] starting screenresolution argv=screenresolution get 12:30:05 INFO - 2016-05-13 12:30:05.486 screenresolution[1645:7927] Display 0: 1600x1200x32@60 12:30:05 INFO - 2016-05-13 12:30:05.491 screenresolution[1646:7930] starting screenresolution argv=screenresolution list 12:30:05 INFO - Available Modes on Display 0 12:30:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:30:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:30:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:30:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:30:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:30:05 INFO - Intel Iris: 12:30:05 INFO - Chipset Model: Intel Iris 12:30:05 INFO - Type: GPU 12:30:05 INFO - Bus: Built-In 12:30:05 INFO - VRAM (Dynamic, Max): 1536 MB 12:30:05 INFO - Vendor: Intel (0x8086) 12:30:05 INFO - Device ID: 0x0a2e 12:30:05 INFO - Revision ID: 0x0009 12:30:05 INFO - Displays: 12:30:05 INFO - Display: 12:30:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:30:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:30:05 INFO - Display Serial Number: 1600x1200 60 12:30:05 INFO - Main Display: Yes 12:30:05 INFO - Mirror: Off 12:30:05 INFO - Online: Yes 12:30:05 INFO - Rotation: Supported 12:30:05 INFO - Return code: 0 12:30:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:30:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:30:05 INFO - Python 2.7.3 12:30:05 INFO - Return code: 0 12:30:05 INFO - grabbing minidump binary from tooltool 12:30:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:30:05 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 0x105339e00>, '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 0x1051a2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105334b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:30:05 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 12:30:05 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 12:30:05 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:30:05 INFO - Return code: 0 12:30:05 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:30:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:30:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:30:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:30:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:30:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 12:30:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 12:30:06 INFO - Return code: 0 12:30:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AT41oYU2Te-vURtmU60Fxw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0273', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 12:30:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AT41oYU2Te-vURtmU60Fxw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0273 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 12:30:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VzFHyujtAv/Render', 12:30:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:30:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:30:06 INFO - 'HOME': '/Users/cltbld', 12:30:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:30:06 INFO - 'LOGNAME': 'cltbld', 12:30:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:30:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:30:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:30:06 INFO - 'MOZ_NO_REMOTE': '1', 12:30:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:30:06 INFO - 'NO_EM_RESTART': '1', 12:30:06 INFO - 'PAGER': '/bin/cat', 12:30:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:30:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:30:06 INFO - 'PWD': '/builds/slave/test', 12:30:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:30:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:30:06 INFO - 'SHELL': '/bin/bash', 12:30:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.lkbOIc2dZ2/Listeners', 12:30:06 INFO - 'TMPDIR': '/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/', 12:30:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:30:06 INFO - 'USER': 'cltbld', 12:30:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:30:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:30:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:30:06 INFO - 'XPC_FLAGS': '0x0', 12:30:06 INFO - 'XPC_SERVICE_NAME': '0', 12:30:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:30:06 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AT41oYU2Te-vURtmU60Fxw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0273', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 12:30:07 INFO - mozversion application_buildid: 20160513103035 12:30:07 INFO - mozversion application_changeset: ac470f026c69f90c82798f15dbf9d538fc99b68e 12:30:07 INFO - mozversion application_display_name: FirefoxDeveloperEdition 12:30:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:30:07 INFO - mozversion application_name: Firefox 12:30:07 INFO - mozversion application_remotingname: firefox-dev 12:30:07 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:30:07 INFO - mozversion application_vendor: Mozilla 12:30:07 INFO - mozversion application_version: 48.0a2 12:30:07 INFO - mozversion platform_buildid: 20160513103035 12:30:07 INFO - mozversion platform_changeset: ac470f026c69f90c82798f15dbf9d538fc99b68e 12:30:07 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:30:07 INFO - mozversion platform_version: 48.0a2 12:30:07 INFO - using testdate: 1463167807 12:30:07 INFO - actual date: 1463167807 12:30:07 INFO - starting webserver on 'localhost:49280' 12:30:07 INFO - SUITE-START | Running 2 tests 12:30:07 INFO - TEST-START | dromaeo_css 12:30:07 INFO - operating with platform_type : mac_ 12:30:07 INFO - Initialising browser for dromaeo_css test... 12:30:07 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/tmp7xeX0q/profile http://localhost:49280/getInfo.html) 12:30:09 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 12:30:09 INFO - PROCESS | 1664 | colorDepth:24 12:30:09 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 12:30:09 INFO - PROCESS | 1664 | __metrics 12:30:09 INFO - PROCESS | 1664 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:30:09 ERROR - PROCESS | 1664 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 12:30:10 INFO - TEST-INFO | 1664: exit 0 12:30:10 INFO - Browser initialized. 12:30:10 INFO - Running cycle 1/1 for dromaeo_css test... 12:30:10 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/tmp7xeX0q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:30:22 INFO - PROCESS | 1667 | RSS: Main: 211439616 12:30:22 INFO - PROCESS | 1667 | 12:31:13 INFO - PROCESS | 1667 | 1463167873039 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] 12:33:24 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49280/tests/dromaeo/cssquery-ext.html) 12:33:26 INFO - PROCESS | 1667 | RSS: Main: 350367744 12:33:26 INFO - PROCESS | 1667 | 12:36:29 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-ext.html (next: http://localhost:49280/tests/dromaeo/cssquery-jquery.html) 12:36:29 INFO - PROCESS | 1667 | RSS: Main: 354603008 12:36:29 INFO - PROCESS | 1667 | 12:39:32 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49280/tests/dromaeo/cssquery-mootools.html) 12:39:32 INFO - PROCESS | 1667 | RSS: Main: 362467328 12:39:32 INFO - PROCESS | 1667 | 12:42:34 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49280/tests/dromaeo/cssquery-prototype.html) 12:42:35 INFO - PROCESS | 1667 | RSS: Main: 360984576 12:42:35 INFO - PROCESS | 1667 | 12:45:38 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49280/tests/dromaeo/cssquery-yui.html) 12:45:39 INFO - PROCESS | 1667 | RSS: Main: 322727936 12:45:39 INFO - PROCESS | 1667 | 12:48:42 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-yui.html (next: http://localhost:49280/tests/dromaeo/cssquery-dojo.html) 12:48:42 INFO - PROCESS | 1667 | RSS: Main: 399495168 12:48:42 INFO - PROCESS | 1667 | 12:48:42 INFO - PROCESS | 1667 | __start_tp_report 12:48:42 INFO - PROCESS | 1667 | _x_x_mozilla_page_load 12:48:42 INFO - PROCESS | 1667 | _x_x_mozilla_page_load_details 12:48:42 INFO - PROCESS | 1667 | |i|pagename|runs| 12:48:42 INFO - PROCESS | 1667 | |0;dojo.html;3388.271521622851;3464.9307013859725;3474.947875781863;3487.3374058928816;3525.929481410372;5705.372409035002;5723.2559767230305;5735.541156707463;5738.741756620946;5744.396838331978;5673.035583950728;5696.1740547620675;5708.315002199742;5726.255586773724;5728.598998070137;5113.130767769479;5117.232415137722;5147.459516750743;5252.579793616521;5252.789888404459;5216.191490319006;5231.24146998685;5237.214417837316;5239.13554263546;5241.606879484035;5175.611829112813;5206.088934436459;5213.191955246943;5227.764750586232;5229.921551176735;5225.712585807779;5259.447757985433;5272.762725677354;5281.260623512711;5310.150375939831;5090.796368145307;5092.490750924915;5106.106431374532;5160.329157209576;5165.302684137631;5539.723013849291;5539.889202215933;5565.2486914266465;5606.327240731098;5644.689542075184;6199.597026193283;6237.251529816405;6246.3441338659695;6246.531510136712;6253.968730156365;6119.045604113885;6220.284667263278;6244.843878903019;6254.593451425642;6330.461910737593;5826.82519524415;5833.50415214703;5847;5850.912236316416;5866.472017518444;6234.220722409699;6298.307186209513;6299.811005669837;6305.905411418832;6323.051542268697;6060.787872424444;6123.9387606124255;6131.448169664752;6181.505479561624;6222.222222222223;6276;6284.874302513924;6301.52738544611;6311.27420346666;6330.651814150266;6186.072089186658;6231.719572619243;6235.438810507076;6243.282022567463;6272.937270627326;6136.693165341715;6190.81427557174;6194.721237544321;6199;6245.406686364788;4899.97100608869;4914.926276105862;4922.4093108826455;4962.801487940442;4973.229149481836;4437.378766972626;4442.577955094261;4445.355423463579;4448.421705178306;4472.105578884236;5590.021746194399;5618.410066942994;5687.317521897398;5733.483986441241;5740.827775166752;6168.074788781629;6203.689815509207;6299.275583307886;6301.338359472189;6302.968485157545;5703.201551782754;5705.515031222392;5729.5702822288495;5730.570207234474;5731.340895796953;5314.4951229633125;5361.571074314045;5380.435054319319;5383.67697938125;5390.51485366319;4867.951320486892;6219.315875253718;6322.747090116436;6405.103285539935;6408.263049749338;6259.655718935503;6284.685765711788;6289.559730818799;6309.432151106423;6330.050492426173;6200.937990620036;6286.18279623646;6354.364563543609;6377.681115944277;6383.425491705769;5227.712475813867;6139.522803442411;6225.408586184305;6234.1583886174785;6248.968755156377;5470.589705772003;5965.687548739271;6264.624122552661;6287.905681414874;6293.590916590407;5957.821265362046;6054.969725151346;6121.571489995836;6141.815745527641;6146.200993870768;6117.492485019787;6118.676412701642;6119.837230926109;6137.775380989496;6151.507879369729;5156.278121062979;5810.94189058104;5943.405659434022;5970.0746384310505;6003.129546215861;5950.3454619992635;6086.178365920721;6102.786402476069;6113.189524795161;6135.845176233341;5920.200772895776;5925.585209035326;5948.2862056553495;5951.077582974646;5955 12:48:42 INFO - PROCESS | 1667 | |1;ext.html;521.7991073436725;536.8201652446431;546.2734563031169;556.8719194585245;593.3532449629903;30802;31467;31526.527102093485;31621.367572648534;31709.841450792715;32625.347493050125;33301.16747081324;39302;40512.392314115314;40573;9417.482038487897;9597.856032159523;9599.376040557376;9600.663976760814;9601.711948641552;8459.915400846005;9289.767755806091;9523.142917137457;9528.42829430233;9540.666076687317;8564.357673174505;11411.828822567668;11622.302661840275;11653.242539234923;11658.766824663502;11346.092312614972;11455.255408398427;11462.885371146349;11466.48400821965;11469.197156199029;8394.874076888851;8594.742157735278;8601.483910965362;11267.323960562393;11402.201845870792;30680.2329941751;40771.59228407707;41328;41334.586654133374;41337.58662413368;3171.7462602991704;3182.6658200889037;3218.3241519280978;3498.352804731133;3869.361555343365;26841.73158268412;26850.5972410414;27116.45767084667;27125;27422.862885685645;2724.5913113033007;2741.150243424545;2747.32690490831;2770.1689493152176;2786.4845003674386;421690;424005;424540;424767.9999999969;424881;36691.63308366882;37400.62599374026;37423.812880935424;62010.37989620046;62445.68777156085;36180;36243.912682619564;36494.63505364912;36521.08697282536;36525.63474365223;13305.40125694346;15460.922695386676;15563.832712546604;15701.685966280611;15723.921380393025;13007.219566825832;13085.411156497981;13114.213147211198;13138.474461021644;13143.07998440119;7719.614019299012;7885.29032387088;8300.418970671995;8307;8483.490990540588;2725.100716763503;3215.5980502437196;3351.463765797461;3419.829008549563;3441.913952151166;1018.7962407518526;1042.3120740311358;1084.1868598551087;1132.9886701132882;1153.296489141623;27239;29372.118836434514;29892.55161172584;30018.849905750332;30411;1130.9547618095348;1201.813718873576;1202.6392082375253;1209.2804781155198;1228.1832581333313;15372;15429.45996890126;15473.458428954933;15545.22273886301;15694.843051569567;630609.8469507715;640182;640817.7959110175;641027;642533.0860111306;59935.700321498116;61714.69142654258;61820.69089654523;61829;62212.68893655593;1302.1405872124542;1344.173333399952;1356.125299182022;1469.346140967302;1489.9255037248095;28440.288992775346;28691.713082868904;29100.568729371396;29470.852645736635;29585.408291834472;48777;48814.511854881;49111.50888491069;49368;49565.75217123891;359919;361731;361785;362777.18611406774;362793;24127.03491860376;27421;27635.44729105447;28037;28049;42127.3056702405;43121.47224541378;43248.783756081015;43319.35020974751;43525.96197961362;35471;35741.642583574874;35911.92264232077;35929;36429.45355819718;35381.82309088438;35613.93158205258;35957.64042359543;36147;37639.24721505609;36237;37081;37285.067873303386;38100.61899380971;38264;31324;33203.83398082994;33314.833425832716;33392.49911251382;33502.66497334996;24453.755462445148;24637.38406539851;24746.133885313928;25188.370290742147;25231 12:48:42 INFO - PROCESS | 1667 | |2;jquery.html;395.5826602933905;396.2135161704017;398.2115411485257;402.35823860941804;416.2465936654655;14918.25408729562;18089.095545222754;19986.90006549967;20670;20737.481562960933;16158;17412.651746965053;19827.80172198281;20101.798982010176;20137.395878123643;4246.639035681971;5421.918671219935;5428.701421421821;5433.945660543383;5434.592405569579;3688.6495782900715;5352.625316227865;5372.389595157396;5438.211459338394;5949.25634295713;4164.333706606943;5895.557833162508;6190.78332258371;6193.009118541035;6194.597351172182;5917.704114794243;6064.9090263646085;6162.22972128484;6172.259328880518;6175.197224360804;5945.821625351246;5984.611000284967;5985.4912332451795;5992;6009.399060094;13368.933155334162;13640.386182621805;23243.53512929732;25525.361865953313;26289.474210515873;2120.925767598142;2149.6564647095565;2282.9543409131725;2404.6393041043807;2487.601983682602;9698.70903872885;9704.90295097047;9930.957249488773;10065.899341006569;10288.794224115476;1725.239933210122;1793.103448275862;1808.7467754514378;1812.7009043507805;1852.5831687870257;106894.46552767264;107364.70541178343;108174.91825081805;111861.76276474506;114109;11612.593559225556;11729.530818767154;11741.589044383405;11746.412679365998;11866.821997670042;15206;15273.770893436607;15289.770653440208;15308.081515109128;15332.616684582974;8410.873836892451;8785.604647790864;8873.112688731204;8875.600597973107;8978.955105224431;8812.118788121137;8824.382293239412;8848.557572121368;8922.241609463268;8945.59744811485;4639.234526303189;4697.436307643105;4699.271612900006;4723.527647235318;4804.087223427608;1749.0938629702323;1954.7458830351964;1988.9403317900485;1990.3829812758092;1994.042859427454;858.3733874271817;925.9722208333761;962;1013.5489707080426;1075.58589942871;15499.690006199813;18690.81309186918;20384.592308153755;20610.79389206119;20664.400203882786;932.7827185522958;938.9436633801994;957.2772556719772;962.1773383756927;1002.9749256268651;10339.586416543405;10729.731756706145;10743.946280268548;10814.621488247874;10893.618723344645;608514;609635;610905;625159;627080;104197.47901260445;117490;122038;122271;123106.38446807709;1003.1523362758458;1034.8447732840134;1044.962323469238;1046.080442388211;1051.2431049644244;17308.394206202724;17386.13069346553;17694.911525442807;17744.3789467368;17746.5563360917;50473;50520.736981575756;51030.48969510257;51450.970980580925;52324.47675523196;70203.6489817568;73140.26859731547;73348;74475.51048979099;75475;7813.804654883674;7842.215778422112;7859.17478664732;7979.84040319202;8046.959765201136;11031.282966607145;11767.588134415255;12165.878341216829;12298.20061695987;12330.383480826102;10092.495375231063;10283.177345812466;10583.047525722723;10590.629327973484;10748.731281717706;10015.399076055168;10207.642732504624;10225.642102526375;10534.315269507457;10647.254692171473;10273.589056437957;10559.52482138321;10660.413677247825;10904.127669786556;10918.50866710984;7876.56678882667;7886.093099293655;7886.487378320391;7957.920420795949;8020.278174964281;10225.43760093202;10397.272190946562;10643.201759868196;10645.0951669109;10662.680119596425 12:48:42 INFO - PROCESS | 1667 | |3;mootools.html;1256.7934782608695;1384.1487485196608;1539.4688832352842;1554.5724925645445;1933.5552822850752;7205.351518363346;7280.745173918921;8146.918530814697;8814.647414103429;8822.206001459866;7415.88876166858;8688.869666955;8753.737387878375;8775.385722999392;8795.076516965722;2165.707629470025;2231.509068005036;2440.3045132137345;2468.827182097254;2483.2301986384186;2446.143849652616;2511.660925775015;2513.49730053989;2514.710808257046;2514.924552263435;2414.163358054288;2459.114718701266;2459.434330104077;2459.8401103928186;2475.9504809903883;2154.547545015533;2381.416552944522;2393.018862266471;2395.928122156329;2398.9520209595885;2429.3197904586746;2464.6795916530914;2468.9135880244285;2471.5056988602173;2492.763187497185;8632;8718.564071796385;8729.301655867515;8731.432456890281;8737.344699147527;5296.470352964711;5390.272313237702;5405.864853378657;5408.891822163535;5454.754536045887;3414.726821854246;3452.154222215548;3457.4122399192197;3576.481410195533;3577.964220357789;1061.9150467962545;1072.1369297715337;1343.5969209237287;1624.5694890853933;1640.5406486183767;11446.713832154262;11689.473973671205;11704.882951170464;11720.238184517979;11734;4735.052989402134;4804.73573953429;4811.662718762556;4868.586170175575;4886.926696099561;10258.487075646188;10259;10291.639792607222;10295.691129266133;10327.431991240404;4819.277108433693;5495.972520137374;5517.862053448696;5522.30971128609;5529.889402211933;4391.209582275157;4493.640508759291;4512.751798651106;5008.248762685626;5008.574271186917;3581.6955558777795;3637.7817330959697;3722.739408241451;3855.652991230803;3867.8839634811;1253.3921048291572;1276.125853790138;1352.864713528659;1358.6739182596107;1361.319340329835;896.9372143949861;911.8103751916188;918.4213945214606;919.0763282900775;962.2398305338844;5617.606767526234;5772.131163914928;5829.795957141479;5880.411958804086;6002.579819412599;933.3513208320152;979.5640939781871;980.4019548075668;980.8185485685171;982.3741707474142;3667.44988251759;3809.447630093621;3869.052082239813;3897.415387691812;3926.7447615904875;10476.319039262424;10839.295445795999;10873.07578855806;10915.072218861485;10928.344299342065;10236.48817559119;10617.256792024638;10781.9460902695;10783.568657253621;10821.513031913582;8230.176982301842;8288.590939540292;8459.450135741157;8469.237768600855;8475.915240847637;4855;5551.195076713934;5663.8867222656145;5689.089745640677;5899.144624029406;9455.858162127712;9628.566714497725;9820.901790982;9827.852582211415;9851.605935762489;9811.362261452983;9817.361871478332;9970.55132519024;9980.750481238027;10057.145142662663;7431.8142046449275;7492.518971090077;7641.350485208597;7646.885296720665;7664.73173438927;7124.643767811485;8238.09380968105;8283.668653253802;8360.205780450848;8363.247307742333;6220.409061139185;7354.866327196161;7414.332710056121;7428.145763237297;7554.811129721801;7209.459290553124;7230.49386542958;7270.127584689871;7358.969744235918;7402.6668799903055;6817.522773405814;6820.045193672989;6866.416354609714;6872.312768723088;6895.103636527219;4754.4532378776885;4853.563179313879;4899.632527560376;4935.988122434964;4942.975285123552;4838.225883858566;4883.145449546386;4953.504649535162;4997.550220480174;5162.948370516397 12:48:42 INFO - PROCESS | 1667 | |4;prototype.html;317.72040604268227;320.2633941933552;330.89576783313254;331.4349034895503;333.3682671337816;5446.91829622555;6649.833754156149;6674.966625166873;6676.76631317904;6692.996050592414;6386.361363863612;6629.436497897685;6653.068570400148;6662.967240077784;6666.966665166679;4826.638002149844;4860.927086093711;4869.366982292315;4870.245112007645;4875.244337127733;4869.147899117658;4874.244492103712;4883.609311255091;4886.828960986366;4890.828820991266;4818.373611430509;4825.517448255181;4828.7102773833685;4829.22732362822;4837.984023355082;4812.157872372339;4817.879553011167;4822.107910036637;4838.225883858601;4838.733869637169;3926.725129240954;4065.166640838621;4096.160287141129;4096.180763847243;4098.508179018537;6322.178116844781;6326.240851097852;6335.5248356372995;6392.520560957947;6395.328490508523;5638.971805140989;5639.661620302795;5653.236813030229;5656.717164141776;5668.404817494145;7061.752838650674;7075.257098004687;7081.964590177067;7089.475762711042;7102.183248926336;4070.738071197938;4385.101054283864;4583.679142460029;4586.289125185602;4591.517890621503;7106;7383;7443.144038435595;7454.217307182722;7473.626318684099;4128.876133715993;4584.701994370355;4610.884727881763;4638.930416043762;4640.95359046412;3980.661643760313;4520.711597194783;4729.952700472952;4733.715977041388;4733.83431579893;5507.614466987273;5610.074337734228;5623.10030395143;5626.2967129108865;5633.915491267634;7087.787366379017;7115.146182458035;7124.92875071253;7128.607926564089;7136.393406560501;1563.9452619158276;1603.6712473942814;1634.811996620404;1640.491447651232;1642.5729310379388;866.9956650216709;1017.7811770469384;1031.128696251666;1043.2175868098925;1043.8642976413018;6522.249941256721;6565.540412171103;6590.934090659128;6604.471642268607;6621.966890165519;6562.310957349505;6684.16447944007;7292.744753933587;7333.74331898381;7344.26557344272;6584.835379115465;6607.603543787292;6608.43828274602;6609.834754131089;6619.801405957829;3676.0442285006093;3877.689784817208;3899.1032062625754;3914.784686842194;3917.059905622687;6411.775587854403;7609.695612175562;7624.580648064299;7634.198409167068;7684.654190561439;5974.641521508724;6040.879182416327;7554.735584254525;7561.243875612395;7590.506617069873;7645.00614920068;7650.770476885702;7660.693572257047;7664.770056898302;7671.347935425328;7275.636218189175;7438.218987006287;7482.70069197226;7482.812929676802;7488.9625551871895;5777.3933736957015;7124.073870396815;7201.171865235565;7343.3390994810725;7370.304829889263;7215.494915356085;7669.309762121436;7671.53970761756;7746.070471543451;7816;4657.928676404378;4668;4688.578027977498;4688.671792974459;4712.858614241578;7419;7427.2201418850245;7448.776536703907;7449.143348514774;7453.888191677021;6924.826879328057;7114.6442677866935;7121.465890058163;7190.640467976685;7210.062691850026;5788.392218817132;6164.722587483484;7107.715691372494;7130.108736407949;7153.177384600837;7007.124109486314;7026.402755765817;7034.824129396806;7037;7051.964740176266;7010.439164866901;7197.928020719726;7206.963965180141;7211.314925082109;7214.567125972459;5744.8947409918;6326.9683651581445;6782.932170678428;6917.23910369869;7003.6498175092065 12:48:42 INFO - PROCESS | 1667 | |5;yui.html;684.8869936460484;742.1057625561199;744.5495475237482;763.5075376382233;779.6374685771117;15127;15266.007709498903;15292;15317.69364612707;15352.769708454369;13995.370208340624;14070.577882663536;14071.648208794786;14073.014888957778;14102.224377659224;724.4349407462174;748.2255865179541;908.6819613135383;935.5088578496276;944.8204841080208;921.4010892919622;929.4376901974309;931.8648795924587;933.1275257634134;934.4860326820256;985.8373368394207;989.3766926836083;989.4014121456523;990.5493000684708;991.3358050106445;774.2257742257742;793.1711361627056;954.4654993203828;974.5176137811758;982.9705108846711;724.1527412926888;725.629928736343;726.9127704675473;727.5816405566767;728.8141523911422;15039.548813535503;15093.169875656835;15130.773038404433;15136.167510786903;15140.394384224617;12442.81335779955;12535.122541422104;12721.745565088739;12929.612111636667;12939;10282.897171028344;10369.792604147875;10395.428251446167;10621.57513699451;10724.624638137706;3509.7718648288037;3541.5218945442293;3565.607783143878;3610.025293170829;3784.375578029649;80735;81731.18268817355;82353;82627.5868620653;83241.58379208126;9343.859842102374;9390.671326503676;9502.334836561375;9546.284028697737;9606.471644059504;17610.559736006446;21164.259250926145;21200.93995300229;21219.575608488052;21278.36164915055;7353.889691654629;7370.299821516947;7525.962370188224;7656.157822639505;7732.994710687574;4001.4397984282223;4014.1971605678395;4031.294523458383;4084.7753373564146;4281.850135245314;3500.9322156742437;3574;3578.087587665129;3580.7314451416246;3584.301061293023;611.3641812514935;619.755196697311;620.6710443464971;632.8734253149388;641.2753588445121;507.23378911581364;513.7379936232473;586.2554555714219;589.4429763873098;604.2597817673314;7055.329743674445;7839.137694853562;7914.722984695508;7914.881276780853;8062.596870156469;517.2938938349126;576.1674380520192;588.1853632718716;600.1747597054051;602.4487593851577;3745.6441638044344;3771.340015497277;3903.59012303704;3907.3943538751537;3933.9009110767383;67142.98571042779;75095;82865.17134828574;83966;91655;67637.32362676409;68672.65663671649;70381.2961870385;71864.56270874554;75075.24924750792;64099;64138;64459.388515287494;64996.67501662461;65386;8242.052164001216;8248.670053197804;8273.586320684062;8401.62192701342;8633.223009929137;44467.33299000582;45209;48815.30316651655;50386.74806625943;50747.746261268454;58784.706076469345;59180.52048698817;59369;59598.51003724941;61319.38680613315;10663;10832.729181770204;11030.28303160292;11033.007029367396;11328.564358662927;11688.064954803765;11901.809819018028;12031;12141.39293035327;12404.56584019555;8611.913880861111;9308.674196403093;10433.113185379329;11284.605038823602;11893.524259029542;10829.945850270698;10967.396793176453;11074;11294.322340659586;12023.27860328383;10843.566257349617;11099.889001110208;11206.215564910379;11415.657530274106;11450.484728187084;9514.524273786421;9579.425234485952;9755.707328780149;9823.557939892866;10136.746581335525;8423.957880210804;9303.209227215491;9327.720168394959;9787.80424391494;9801.362911410735 12:48:42 INFO - PROCESS | 1667 | __end_tp_report 12:48:42 INFO - PROCESS | 1667 | __start_cc_report 12:48:42 INFO - PROCESS | 1667 | _x_x_mozilla_cycle_collect,1368 12:48:42 INFO - PROCESS | 1667 | __end_cc_report 12:48:42 INFO - PROCESS | 1667 | __startTimestamp1463168922295__endTimestamp 12:48:42 INFO - PROCESS | 1667 | 12:48:42 INFO - PROCESS | 1667 | ------- Summary: start ------- 12:48:42 INFO - PROCESS | 1667 | Number of tests: 6 12:48:42 INFO - PROCESS | 1667 | 12:48:42 INFO - PROCESS | 1667 | [#0] dojo.html Cycles:165 Average:5730.76 Median:5943.41 stddev:634.23 (10.7%) stddev-sans-first:608.97 12:48:42 INFO - PROCESS | 1667 | Values: 3388.3 3464.9 3474.9 3487.3 3525.9 5705.4 5723.3 5735.5 5738.7 5744.4 5673.0 5696.2 5708.3 5726.3 5728.6 5113.1 5117.2 5147.5 5252.6 5252.8 5216.2 5231.2 5237.2 5239.1 5241.6 5175.6 5206.1 5213.2 5227.8 5229.9 5225.7 5259.4 5272.8 5281.3 5310.2 5090.8 5092.5 5106.1 5160.3 5165.3 5539.7 5539.9 5565.2 5606.3 5644.7 6199.6 6237.3 6246.3 6246.5 6254.0 6119.0 6220.3 6244.8 6254.6 6330.5 5826.8 5833.5 5847.0 5850.9 5866.5 6234.2 6298.3 6299.8 6305.9 6323.1 6060.8 6123.9 6131.4 6181.5 6222.2 6276.0 6284.9 6301.5 6311.3 6330.7 6186.1 6231.7 6235.4 6243.3 6272.9 6136.7 6190.8 6194.7 6199.0 6245.4 4900.0 4914.9 4922.4 4962.8 4973.2 4437.4 4442.6 4445.4 4448.4 4472.1 5590.0 5618.4 5687.3 5733.5 5740.8 6168.1 6203.7 6299.3 6301.3 6303.0 5703.2 5705.5 5729.6 5730.6 5731.3 5314.5 5361.6 5380.4 5383.7 5390.5 4868.0 6219.3 6322.7 6405.1 6408.3 6259.7 6284.7 6289.6 6309.4 6330.1 6200.9 6286.2 6354.4 6377.7 6383.4 5227.7 6139.5 6225.4 6234.2 6249.0 5470.6 5965.7 6264.6 6287.9 6293.6 5957.8 6055.0 6121.6 6141.8 6146.2 6117.5 6118.7 6119.8 6137.8 6151.5 5156.3 5810.9 5943.4 5970.1 6003.1 5950.3 6086.2 6102.8 6113.2 6135.8 5920.2 5925.6 5948.3 5951.1 5955.0 12:48:42 INFO - PROCESS | 1667 | 12:48:42 INFO - PROCESS | 1667 | [#1] ext.html Cycles:180 Average:60168.81 Median:3430.87 stddev:131019.22 (3818.8%) stddev-sans-first:131310.22 12:48:42 INFO - PROCESS | 1667 | Values: 521.8 536.8 546.3 556.9 593.4 30802.0 31467.0 31526.5 31621.4 31709.8 32625.3 33301.2 39302.0 40512.4 40573.0 9417.5 9597.9 9599.4 9600.7 9601.7 8459.9 9289.8 9523.1 9528.4 9540.7 8564.4 11411.8 11622.3 11653.2 11658.8 11346.1 11455.3 11462.9 11466.5 11469.2 8394.9 8594.7 8601.5 11267.3 11402.2 30680.2 40771.6 41328.0 41334.6 41337.6 3171.7 3182.7 3218.3 3498.4 3869.4 26841.7 26850.6 27116.5 27125.0 27422.9 2724.6 2741.2 2747.3 2770.2 2786.5 421690.0 424005.0 424540.0 424768.0 424881.0 36691.6 37400.6 37423.8 62010.4 62445.7 36180.0 36243.9 36494.6 36521.1 36525.6 13305.4 15460.9 15563.8 15701.7 15723.9 13007.2 13085.4 13114.2 13138.5 13143.1 7719.6 7885.3 8300.4 8307.0 8483.5 2725.1 3215.6 3351.5 3419.8 3441.9 1018.8 1042.3 1084.2 1133.0 1153.3 27239.0 29372.1 29892.6 30018.8 30411.0 1131.0 1201.8 1202.6 1209.3 1228.2 15372.0 15429.5 15473.5 15545.2 15694.8 630609.8 640182.0 640817.8 641027.0 642533.1 59935.7 61714.7 61820.7 61829.0 62212.7 1302.1 1344.2 1356.1 1469.3 1489.9 28440.3 28691.7 29100.6 29470.9 29585.4 48777.0 48814.5 49111.5 49368.0 49565.8 359919.0 361731.0 361785.0 362777.2 362793.0 24127.0 27421.0 27635.4 28037.0 28049.0 42127.3 43121.5 43248.8 43319.4 43526.0 35471.0 35741.6 35911.9 35929.0 36429.5 35381.8 35613.9 35957.6 36147.0 37639.2 36237.0 37081.0 37285.1 38100.6 38264.0 31324.0 33203.8 33314.8 33392.5 33502.7 24453.8 24637.4 24746.1 25188.4 25231.0 12:48:42 INFO - PROCESS | 1667 | 12:48:42 INFO - PROCESS | 1667 | [#2] jquery.html Cycles:180 Average:34453.16 Median:20703.74 stddev:102287.71 (494.1%) stddev-sans-first:102542.69 12:48:42 INFO - PROCESS | 1667 | Values: 395.6 396.2 398.2 402.4 416.2 14918.3 18089.1 19986.9 20670.0 20737.5 16158.0 17412.7 19827.8 20101.8 20137.4 4246.6 5421.9 5428.7 5433.9 5434.6 3688.6 5352.6 5372.4 5438.2 5949.3 4164.3 5895.6 6190.8 6193.0 6194.6 5917.7 6064.9 6162.2 6172.3 6175.2 5945.8 5984.6 5985.5 5992.0 6009.4 13368.9 13640.4 23243.5 25525.4 26289.5 2120.9 2149.7 2283.0 2404.6 2487.6 9698.7 9704.9 9931.0 10065.9 10288.8 1725.2 1793.1 1808.7 1812.7 1852.6 106894.5 107364.7 108174.9 111861.8 114109.0 11612.6 11729.5 11741.6 11746.4 11866.8 15206.0 15273.8 15289.8 15308.1 15332.6 8410.9 8785.6 8873.1 8875.6 8979.0 8812.1 8824.4 8848.6 8922.2 8945.6 4639.2 4697.4 4699.3 4723.5 4804.1 1749.1 1954.7 1988.9 1990.4 1994.0 858.4 926.0 962.0 1013.5 1075.6 15499.7 18690.8 20384.6 20610.8 20664.4 932.8 938.9 957.3 962.2 1003.0 10339.6 10729.7 10743.9 10814.6 10893.6 608514.0 609635.0 610905.0 625159.0 627080.0 104197.5 117490.0 122038.0 122271.0 123106.4 1003.2 1034.8 1045.0 1046.1 1051.2 17308.4 17386.1 17694.9 17744.4 17746.6 50473.0 50520.7 51030.5 51451.0 52324.5 70203.6 73140.3 73348.0 74475.5 75475.0 7813.8 7842.2 7859.2 7979.8 8047.0 11031.3 11767.6 12165.9 12298.2 12330.4 10092.5 10283.2 10583.0 10590.6 10748.7 10015.4 10207.6 10225.6 10534.3 10647.3 10273.6 10559.5 10660.4 10904.1 10918.5 7876.6 7886.1 7886.5 7957.9 8020.3 10225.4 10397.3 10643.2 10645.1 10662.7 12:48:42 INFO - PROCESS | 1667 | 12:48:42 INFO - PROCESS | 1667 | [#3] mootools.html Cycles:180 Average:5678.36 Median:4917.81 stddev:3160.61 (64.3%) stddev-sans-first:3152.01 12:48:42 INFO - PROCESS | 1667 | Values: 1256.8 1384.1 1539.5 1554.6 1933.6 7205.4 7280.7 8146.9 8814.6 8822.2 7415.9 8688.9 8753.7 8775.4 8795.1 2165.7 2231.5 2440.3 2468.8 2483.2 2446.1 2511.7 2513.5 2514.7 2514.9 2414.2 2459.1 2459.4 2459.8 2476.0 2154.5 2381.4 2393.0 2395.9 2399.0 2429.3 2464.7 2468.9 2471.5 2492.8 8632.0 8718.6 8729.3 8731.4 8737.3 5296.5 5390.3 5405.9 5408.9 5454.8 3414.7 3452.2 3457.4 3576.5 3578.0 1061.9 1072.1 1343.6 1624.6 1640.5 11446.7 11689.5 11704.9 11720.2 11734.0 4735.1 4804.7 4811.7 4868.6 4886.9 10258.5 10259.0 10291.6 10295.7 10327.4 4819.3 5496.0 5517.9 5522.3 5529.9 4391.2 4493.6 4512.8 5008.2 5008.6 3581.7 3637.8 3722.7 3855.7 3867.9 1253.4 1276.1 1352.9 1358.7 1361.3 896.9 911.8 918.4 919.1 962.2 5617.6 5772.1 5829.8 5880.4 6002.6 933.4 979.6 980.4 980.8 982.4 3667.4 3809.4 3869.1 3897.4 3926.7 10476.3 10839.3 10873.1 10915.1 10928.3 10236.5 10617.3 10781.9 10783.6 10821.5 8230.2 8288.6 8459.5 8469.2 8475.9 4855.0 5551.2 5663.9 5689.1 5899.1 9455.9 9628.6 9820.9 9827.9 9851.6 9811.4 9817.4 9970.6 9980.8 10057.1 7431.8 7492.5 7641.4 7646.9 7664.7 7124.6 8238.1 8283.7 8360.2 8363.2 6220.4 7354.9 7414.3 7428.1 7554.8 7209.5 7230.5 7270.1 7359.0 7402.7 6817.5 6820.0 6866.4 6872.3 6895.1 4754.5 4853.6 4899.6 4936.0 4943.0 4838.2 4883.1 4953.5 4997.6 5162.9 12:48:42 INFO - PROCESS | 1667 | 12:48:42 INFO - PROCESS | 1667 | [#4] prototype.html Cycles:180 Average:5746.38 Median:6563.93 stddev:1856.26 (28.3%) stddev-sans-first:1816.19 12:48:42 INFO - PROCESS | 1667 | Values: 317.7 320.3 330.9 331.4 333.4 5446.9 6649.8 6675.0 6676.8 6693.0 6386.4 6629.4 6653.1 6663.0 6667.0 4826.6 4860.9 4869.4 4870.2 4875.2 4869.1 4874.2 4883.6 4886.8 4890.8 4818.4 4825.5 4828.7 4829.2 4838.0 4812.2 4817.9 4822.1 4838.2 4838.7 3926.7 4065.2 4096.2 4096.2 4098.5 6322.2 6326.2 6335.5 6392.5 6395.3 5639.0 5639.7 5653.2 5656.7 5668.4 7061.8 7075.3 7082.0 7089.5 7102.2 4070.7 4385.1 4583.7 4586.3 4591.5 7106.0 7383.0 7443.1 7454.2 7473.6 4128.9 4584.7 4610.9 4638.9 4641.0 3980.7 4520.7 4730.0 4733.7 4733.8 5507.6 5610.1 5623.1 5626.3 5633.9 7087.8 7115.1 7124.9 7128.6 7136.4 1563.9 1603.7 1634.8 1640.5 1642.6 867.0 1017.8 1031.1 1043.2 1043.9 6522.2 6565.5 6590.9 6604.5 6622.0 6562.3 6684.2 7292.7 7333.7 7344.3 6584.8 6607.6 6608.4 6609.8 6619.8 3676.0 3877.7 3899.1 3914.8 3917.1 6411.8 7609.7 7624.6 7634.2 7684.7 5974.6 6040.9 7554.7 7561.2 7590.5 7645.0 7650.8 7660.7 7664.8 7671.3 7275.6 7438.2 7482.7 7482.8 7489.0 5777.4 7124.1 7201.2 7343.3 7370.3 7215.5 7669.3 7671.5 7746.1 7816.0 4657.9 4668.0 4688.6 4688.7 4712.9 7419.0 7427.2 7448.8 7449.1 7453.9 6924.8 7114.6 7121.5 7190.6 7210.1 5788.4 6164.7 7107.7 7130.1 7153.2 7007.1 7026.4 7034.8 7037.0 7052.0 7010.4 7197.9 7207.0 7211.3 7214.6 5744.9 6327.0 6782.9 6917.2 7003.6 12:48:42 INFO - PROCESS | 1667 | 12:48:42 INFO - PROCESS | 1667 | [#5] yui.html Cycles:180 Average:17313.93 Median:601.31 stddev:23633.42 (3930.3%) stddev-sans-first:23666.73 12:48:42 INFO - PROCESS | 1667 | Values: 684.9 742.1 744.5 763.5 779.6 15127.0 15266.0 15292.0 15317.7 15352.8 13995.4 14070.6 14071.6 14073.0 14102.2 724.4 748.2 908.7 935.5 944.8 921.4 929.4 931.9 933.1 934.5 985.8 989.4 989.4 990.5 991.3 774.2 793.2 954.5 974.5 983.0 724.2 725.6 726.9 727.6 728.8 15039.5 15093.2 15130.8 15136.2 15140.4 12442.8 12535.1 12721.7 12929.6 12939.0 10282.9 10369.8 10395.4 10621.6 10724.6 3509.8 3541.5 3565.6 3610.0 3784.4 80735.0 81731.2 82353.0 82627.6 83241.6 9343.9 9390.7 9502.3 9546.3 9606.5 17610.6 21164.3 21200.9 21219.6 21278.4 7353.9 7370.3 7526.0 7656.2 7733.0 4001.4 4014.2 4031.3 4084.8 4281.9 3500.9 3574.0 3578.1 3580.7 3584.3 611.4 619.8 620.7 632.9 641.3 507.2 513.7 586.3 589.4 604.3 7055.3 7839.1 7914.7 7914.9 8062.6 517.3 576.2 588.2 600.2 602.4 3745.6 3771.3 3903.6 3907.4 3933.9 67143.0 75095.0 82865.2 83966.0 91655.0 67637.3 68672.7 70381.3 71864.6 75075.2 64099.0 64138.0 64459.4 64996.7 65386.0 8242.1 8248.7 8273.6 8401.6 8633.2 44467.3 45209.0 48815.3 50386.7 50747.7 58784.7 59180.5 59369.0 59598.5 61319.4 10663.0 10832.7 11030.3 11033.0 11328.6 11688.1 11901.8 12031.0 12141.4 12404.6 8611.9 9308.7 10433.1 11284.6 11893.5 10829.9 10967.4 11074.0 11294.3 12023.3 10843.6 11099.9 11206.2 11415.7 11450.5 9514.5 9579.4 9755.7 9823.6 10136.7 8424.0 9303.2 9327.7 9787.8 9801.4 12:48:42 INFO - PROCESS | 1667 | -------- Summary: end -------- 12:48:42 INFO - PROCESS | 1667 | 12:48:42 INFO - TEST-INFO | 1667: exit 0 12:48:42 INFO - TEST-OK | dromaeo_css | took 1115791ms 12:48:42 INFO - TEST-START | kraken 12:48:42 INFO - operating with platform_type : mac_ 12:48:42 INFO - Initialising browser for kraken test... 12:48:42 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/tmp1XTpyu/profile http://localhost:49280/getInfo.html) 12:48:44 INFO - PROCESS | 1674 | __metrics Screen width/height:1600/1200 12:48:44 INFO - PROCESS | 1674 | colorDepth:24 12:48:44 INFO - PROCESS | 1674 | Browser inner width/height: 1024/689 12:48:44 INFO - PROCESS | 1674 | __metrics 12:48:45 INFO - PROCESS | 1674 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:48:45 ERROR - PROCESS | 1674 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 12:48:45 INFO - TEST-INFO | 1674: exit 0 12:48:45 INFO - Browser initialized. 12:48:45 INFO - Running cycle 1/1 for kraken test... 12:48:45 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/tmp1XTpyu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:48:57 INFO - PROCESS | 1676 | RSS: Main: 211492864 12:48:57 INFO - PROCESS | 1676 | 12:49:00 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection) 12:49:01 INFO - PROCESS | 1676 | RSS: Main: 272158720 12:49:01 INFO - PROCESS | 1676 | 12:49:03 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-dft) 12:49:04 INFO - PROCESS | 1676 | RSS: Main: 286273536 12:49:04 INFO - PROCESS | 1676 | 12:49:07 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-fft) 12:49:08 INFO - PROCESS | 1676 | RSS: Main: 374579200 12:49:08 INFO - PROCESS | 1676 | 12:49:10 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator) 12:49:11 INFO - PROCESS | 1676 | RSS: Main: 279035904 12:49:11 INFO - PROCESS | 1676 | 12:49:13 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:49:13 INFO - PROCESS | 1676 | RSS: Main: 280416256 12:49:13 INFO - PROCESS | 1676 | 12:49:16 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom) 12:49:17 INFO - PROCESS | 1676 | RSS: Main: 317976576 12:49:17 INFO - PROCESS | 1676 | 12:49:20 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate) 12:49:20 INFO - PROCESS | 1676 | RSS: Main: 317390848 12:49:20 INFO - PROCESS | 1676 | 12:49:23 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial) 12:49:24 INFO - PROCESS | 1676 | RSS: Main: 316932096 12:49:24 INFO - PROCESS | 1676 | 12:49:25 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:49:26 INFO - PROCESS | 1676 | RSS: Main: 309035008 12:49:26 INFO - PROCESS | 1676 | 12:49:28 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:49:28 INFO - PROCESS | 1676 | RSS: Main: 314707968 12:49:28 INFO - PROCESS | 1676 | 12:49:30 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:49:31 INFO - PROCESS | 1676 | RSS: Main: 318881792 12:49:31 INFO - PROCESS | 1676 | 12:49:34 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:49:35 INFO - PROCESS | 1676 | RSS: Main: 323883008 12:49:35 INFO - PROCESS | 1676 | 12:49:38 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:49:38 INFO - PROCESS | 1676 | RSS: Main: 321884160 12:49:38 INFO - PROCESS | 1676 | 12:49:40 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49280/tests/kraken/driver.html?testName=ai-astar) 12:49:40 INFO - PROCESS | 1676 | RSS: Main: 386334720 12:49:40 INFO - PROCESS | 1676 | 12:49:40 INFO - PROCESS | 1676 | __start_tp_report 12:49:40 INFO - PROCESS | 1676 | _x_x_mozilla_page_load 12:49:40 INFO - PROCESS | 1676 | _x_x_mozilla_page_load_details 12:49:40 INFO - PROCESS | 1676 | |i|pagename|runs| 12:49:40 INFO - PROCESS | 1676 | |0;ai-astar;113;115;121;112;113;112;113;115;115;133 12:49:40 INFO - PROCESS | 1676 | |1;audio-beat-detection;156;121;154;177;151;141;118;156;190;119 12:49:40 INFO - PROCESS | 1676 | |2;audio-dft;182;185;184;187;184;181;185;185;194;232 12:49:40 INFO - PROCESS | 1676 | |3;audio-fft;69;78;76;160;57;56;60;66;140;60 12:49:40 INFO - PROCESS | 1676 | |4;audio-oscillator;90;88;148;78;81;85;129;79;77;124 12:49:40 INFO - PROCESS | 1676 | |5;imaging-gaussian-blur;87;90;134;112;86;83;87;85;88;83 12:49:40 INFO - PROCESS | 1676 | |6;imaging-darkroom;99;142;93;93;92;93;92;93;92;92 12:49:40 INFO - PROCESS | 1676 | |7;imaging-desaturate;84;85;86;85;83;84;84;83;83;84 12:49:40 INFO - PROCESS | 1676 | |8;json-parse-financial;59;63;63;63;64;64;63;83;57;58 12:49:40 INFO - PROCESS | 1676 | |9;json-stringify-tinderbox;64;65;73;77;65;71;71;67;69;68 12:49:40 INFO - PROCESS | 1676 | |10;stanford-crypto-aes;82;81;81;81;82;80;81;80;82;84 12:49:40 INFO - PROCESS | 1676 | |11;stanford-crypto-ccm;130;128;129;139;139;119;144;120;133;183 12:49:40 INFO - PROCESS | 1676 | |12;stanford-crypto-pbkdf2;170;168;171;166;169;216;160;212;214;164 12:49:40 INFO - PROCESS | 1676 | |13;stanford-crypto-sha256-iterative;69;70;96;94;92;79;70;74;93;93 12:49:40 INFO - PROCESS | 1676 | __end_tp_report 12:49:40 INFO - PROCESS | 1676 | __start_cc_report 12:49:40 INFO - PROCESS | 1676 | _x_x_mozilla_cycle_collect,2044 12:49:40 INFO - PROCESS | 1676 | __end_cc_report 12:49:40 INFO - PROCESS | 1676 | __startTimestamp1463168980819__endTimestamp 12:49:40 INFO - PROCESS | 1676 | 12:49:40 INFO - PROCESS | 1676 | ------- Summary: start ------- 12:49:40 INFO - PROCESS | 1676 | Number of tests: 14 12:49:40 INFO - PROCESS | 1676 | 12:49:40 INFO - PROCESS | 1676 | [#0] ai-astar Cycles:10 Average:116.20 Median:115.00 stddev:6.46 (5.6%) stddev-sans-first:6.75 12:49:40 INFO - PROCESS | 1676 | Values: 113.0 115.0 121.0 112.0 113.0 112.0 113.0 115.0 115.0 133.0 12:49:40 INFO - PROCESS | 1676 | 12:49:40 INFO - PROCESS | 1676 | [#1] audio-beat-detection Cycles:10 Average:148.30 Median:155.00 stddev:24.26 (15.7%) stddev-sans-first:25.57 12:49:40 INFO - PROCESS | 1676 | Values: 156.0 121.0 154.0 177.0 151.0 141.0 118.0 156.0 190.0 119.0 12:49:40 INFO - PROCESS | 1676 | 12:49:40 INFO - PROCESS | 1676 | [#2] audio-dft Cycles:10 Average:189.90 Median:185.00 stddev:15.21 (8.2%) stddev-sans-first:15.86 12:49:40 INFO - PROCESS | 1676 | Values: 182.0 185.0 184.0 187.0 184.0 181.0 185.0 185.0 194.0 232.0 12:49:40 INFO - PROCESS | 1676 | 12:49:40 INFO - PROCESS | 1676 | [#3] audio-fft Cycles:10 Average:82.20 Median:63.00 stddev:36.81 (58.4%) stddev-sans-first:38.73 12:49:40 INFO - PROCESS | 1676 | Values: 69.0 78.0 76.0 160.0 57.0 56.0 60.0 66.0 140.0 60.0 12:49:40 INFO - PROCESS | 1676 | 12:49:40 INFO - PROCESS | 1676 | [#4] audio-oscillator Cycles:10 Average:97.90 Median:80.00 stddev:25.74 (32.2%) stddev-sans-first:27.14 12:49:40 INFO - PROCESS | 1676 | Values: 90.0 88.0 148.0 78.0 81.0 85.0 129.0 79.0 77.0 124.0 12:49:40 INFO - PROCESS | 1676 | 12:49:40 INFO - PROCESS | 1676 | [#5] imaging-gaussian-blur Cycles:10 Average:93.50 Median:86.50 stddev:16.53 (19.1%) stddev-sans-first:17.36 12:49:40 INFO - PROCESS | 1676 | Values: 87.0 90.0 134.0 112.0 86.0 83.0 87.0 85.0 88.0 83.0 12:49:40 INFO - PROCESS | 1676 | 12:49:40 INFO - PROCESS | 1676 | [#6] imaging-darkroom Cycles:10 Average:98.10 Median:93.00 stddev:15.57 (16.7%) stddev-sans-first:16.51 12:49:40 INFO - PROCESS | 1676 | Values: 99.0 142.0 93.0 93.0 92.0 93.0 92.0 93.0 92.0 92.0 12:49:40 INFO - PROCESS | 1676 | 12:49:40 INFO - PROCESS | 1676 | [#7] imaging-desaturate Cycles:10 Average:84.10 Median:84.00 stddev:0.99 (1.2%) stddev-sans-first:1.05 12:49:40 INFO - PROCESS | 1676 | Values: 84.0 85.0 86.0 85.0 83.0 84.0 84.0 83.0 83.0 84.0 12:49:40 INFO - PROCESS | 1676 | 12:49:40 INFO - PROCESS | 1676 | [#8] json-parse-financial Cycles:10 Average:63.70 Median:63.00 stddev:7.26 (11.5%) stddev-sans-first:7.50 12:49:40 INFO - PROCESS | 1676 | Values: 59.0 63.0 63.0 63.0 64.0 64.0 63.0 83.0 57.0 58.0 12:49:40 INFO - PROCESS | 1676 | 12:49:40 INFO - PROCESS | 1676 | [#9] json-stringify-tinderbox Cycles:10 Average:69.00 Median:70.00 stddev:4.08 (5.8%) stddev-sans-first:3.91 12:49:40 INFO - PROCESS | 1676 | Values: 64.0 65.0 73.0 77.0 65.0 71.0 71.0 67.0 69.0 68.0 12:49:40 INFO - PROCESS | 1676 | 12:49:40 INFO - PROCESS | 1676 | [#10] stanford-crypto-aes Cycles:10 Average:81.40 Median:81.50 stddev:1.17 (1.4%) stddev-sans-first:1.22 12:49:40 INFO - PROCESS | 1676 | Values: 82.0 81.0 81.0 81.0 82.0 80.0 81.0 80.0 82.0 84.0 12:49:40 INFO - PROCESS | 1676 | 12:49:40 INFO - PROCESS | 1676 | [#11] stanford-crypto-ccm Cycles:10 Average:136.40 Median:136.00 stddev:18.23 (13.4%) stddev-sans-first:19.19 12:49:40 INFO - PROCESS | 1676 | Values: 130.0 128.0 129.0 139.0 139.0 119.0 144.0 120.0 133.0 183.0 12:49:40 INFO - PROCESS | 1676 | 12:49:40 INFO - PROCESS | 1676 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.00 Median:170.50 stddev:23.01 (13.5%) stddev-sans-first:24.06 12:49:40 INFO - PROCESS | 1676 | Values: 170.0 168.0 171.0 166.0 169.0 216.0 160.0 212.0 214.0 164.0 12:49:40 INFO - PROCESS | 1676 | 12:49:40 INFO - PROCESS | 1676 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:83.00 Median:92.50 stddev:11.56 (12.5%) stddev-sans-first:11.09 12:49:40 INFO - PROCESS | 1676 | Values: 69.0 70.0 96.0 94.0 92.0 79.0 70.0 74.0 93.0 93.0 12:49:40 INFO - PROCESS | 1676 | -------- Summary: end -------- 12:49:40 INFO - PROCESS | 1676 | 12:49:41 INFO - TEST-INFO | 1676: exit 0 12:49:41 INFO - TEST-OK | kraken | took 58781ms 12:49:41 INFO - SUITE-END | took 1174s 12:49:41 INFO - Completed test suite (00:19:34) 12:49:41 INFO - javascript benchmark 12:49:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3388.271521622851, 3464.9307013859725, 3474.947875781863, 3487.3374058928816, 3525.929481410372, 5705.372409035002, 5723.2559767230305, 5735.541156707463, 5738.741756620946, 5744.396838331978, 5673.035583950728, 5696.1740547620675, 5708.315002199742, 5726.255586773724, 5728.598998070137, 5113.130767769479, 5117.232415137722, 5147.459516750743, 5252.579793616521, 5252.789888404459, 5216.191490319006, 5231.24146998685, 5237.214417837316, 5239.13554263546, 5241.606879484035, 5175.611829112813, 5206.088934436459, 5213.191955246943, 5227.764750586232, 5229.921551176735, 5225.712585807779, 5259.447757985433, 5272.762725677354, 5281.260623512711, 5310.150375939831, 5090.796368145307, 5092.490750924915, 5106.106431374532, 5160.329157209576, 5165.302684137631, 5539.723013849291, 5539.889202215933, 5565.2486914266465, 5606.327240731098, 5644.689542075184, 6199.597026193283, 6237.251529816405, 6246.3441338659695, 6246.531510136712, 6253.968730156365, 6119.045604113885, 6220.284667263278, 6244.843878903019, 6254.593451425642, 6330.461910737593, 5826.82519524415, 5833.50415214703, 5847.0, 5850.912236316416, 5866.472017518444, 6234.220722409699, 6298.307186209513, 6299.811005669837, 6305.905411418832, 6323.051542268697, 6060.787872424444, 6123.9387606124255, 6131.448169664752, 6181.505479561624, 6222.222222222223, 6276.0, 6284.874302513924, 6301.52738544611, 6311.27420346666, 6330.651814150266, 6186.072089186658, 6231.719572619243, 6235.438810507076, 6243.282022567463, 6272.937270627326, 6136.693165341715, 6190.81427557174, 6194.721237544321, 6199.0, 6245.406686364788, 4899.97100608869, 4914.926276105862, 4922.4093108826455, 4962.801487940442, 4973.229149481836, 4437.378766972626, 4442.577955094261, 4445.355423463579, 4448.421705178306, 4472.105578884236, 5590.021746194399, 5618.410066942994, 5687.317521897398, 5733.483986441241, 5740.827775166752, 6168.074788781629, 6203.689815509207, 6299.275583307886, 6301.338359472189, 6302.968485157545, 5703.201551782754, 5705.515031222392, 5729.5702822288495, 5730.570207234474, 5731.340895796953, 5314.4951229633125, 5361.571074314045, 5380.435054319319, 5383.67697938125, 5390.51485366319, 4867.951320486892, 6219.315875253718, 6322.747090116436, 6405.103285539935, 6408.263049749338, 6259.655718935503, 6284.685765711788, 6289.559730818799, 6309.432151106423, 6330.050492426173, 6200.937990620036, 6286.18279623646, 6354.364563543609, 6377.681115944277, 6383.425491705769, 5227.712475813867, 6139.522803442411, 6225.408586184305, 6234.1583886174785, 6248.968755156377, 5470.589705772003, 5965.687548739271, 6264.624122552661, 6287.905681414874, 6293.590916590407, 5957.821265362046, 6054.969725151346, 6121.571489995836, 6141.815745527641, 6146.200993870768, 6117.492485019787, 6118.676412701642, 6119.837230926109, 6137.775380989496, 6151.507879369729, 5156.278121062979, 5810.94189058104, 5943.405659434022, 5970.0746384310505, 6003.129546215861, 5950.3454619992635, 6086.178365920721, 6102.786402476069, 6113.189524795161, 6135.845176233341, 5920.200772895776, 5925.585209035326, 5948.2862056553495, 5951.077582974646, 5955.0], "value": 5692.160006296721, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [521.7991073436725, 536.8201652446431, 546.2734563031169, 556.8719194585245, 593.3532449629903, 30802.0, 31467.0, 31526.527102093485, 31621.367572648534, 31709.841450792715, 32625.347493050125, 33301.16747081324, 39302.0, 40512.392314115314, 40573.0, 9417.482038487897, 9597.856032159523, 9599.376040557376, 9600.663976760814, 9601.711948641552, 8459.915400846005, 9289.767755806091, 9523.142917137457, 9528.42829430233, 9540.666076687317, 8564.357673174505, 11411.828822567668, 11622.302661840275, 11653.242539234923, 11658.766824663502, 11346.092312614972, 11455.255408398427, 11462.885371146349, 11466.48400821965, 11469.197156199029, 8394.874076888851, 8594.742157735278, 8601.483910965362, 11267.323960562393, 11402.201845870792, 30680.2329941751, 40771.59228407707, 41328.0, 41334.586654133374, 41337.58662413368, 3171.7462602991704, 3182.6658200889037, 3218.3241519280978, 3498.352804731133, 3869.361555343365, 26841.73158268412, 26850.5972410414, 27116.45767084667, 27125.0, 27422.862885685645, 2724.5913113033007, 2741.150243424545, 2747.32690490831, 2770.1689493152176, 2786.4845003674386, 421690.0, 424005.0, 424540.0, 424767.9999999969, 424881.0, 36691.63308366882, 37400.62599374026, 37423.812880935424, 62010.37989620046, 62445.68777156085, 36180.0, 36243.912682619564, 36494.63505364912, 36521.08697282536, 36525.63474365223, 13305.40125694346, 15460.922695386676, 15563.832712546604, 15701.685966280611, 15723.921380393025, 13007.219566825832, 13085.411156497981, 13114.213147211198, 13138.474461021644, 13143.07998440119, 7719.614019299012, 7885.29032387088, 8300.418970671995, 8307.0, 8483.490990540588, 2725.100716763503, 3215.5980502437196, 3351.463765797461, 3419.829008549563, 3441.913952151166, 1018.7962407518526, 1042.3120740311358, 1084.1868598551087, 1132.9886701132882, 1153.296489141623, 27239.0, 29372.118836434514, 29892.55161172584, 30018.849905750332, 30411.0, 1130.9547618095348, 1201.813718873576, 1202.6392082375253, 1209.2804781155198, 1228.1832581333313, 15372.0, 15429.45996890126, 15473.458428954933, 15545.22273886301, 15694.843051569567, 630609.8469507715, 640182.0, 640817.7959110175, 641027.0, 642533.0860111306, 59935.700321498116, 61714.69142654258, 61820.69089654523, 61829.0, 62212.68893655593, 1302.1405872124542, 1344.173333399952, 1356.125299182022, 1469.346140967302, 1489.9255037248095, 28440.288992775346, 28691.713082868904, 29100.568729371396, 29470.852645736635, 29585.408291834472, 48777.0, 48814.511854881, 49111.50888491069, 49368.0, 49565.75217123891, 359919.0, 361731.0, 361785.0, 362777.18611406774, 362793.0, 24127.03491860376, 27421.0, 27635.44729105447, 28037.0, 28049.0, 42127.3056702405, 43121.47224541378, 43248.783756081015, 43319.35020974751, 43525.96197961362, 35471.0, 35741.642583574874, 35911.92264232077, 35929.0, 36429.45355819718, 35381.82309088438, 35613.93158205258, 35957.64042359543, 36147.0, 37639.24721505609, 36237.0, 37081.0, 37285.067873303386, 38100.61899380971, 38264.0, 31324.0, 33203.83398082994, 33314.833425832716, 33392.49911251382, 33502.66497334996, 24453.755462445148, 24637.38406539851, 24746.133885313928, 25188.370290742147, 25231.0], "value": 18134.021962098985, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [395.5826602933905, 396.2135161704017, 398.2115411485257, 402.35823860941804, 416.2465936654655, 14918.25408729562, 18089.095545222754, 19986.90006549967, 20670.0, 20737.481562960933, 16158.0, 17412.651746965053, 19827.80172198281, 20101.798982010176, 20137.395878123643, 4246.639035681971, 5421.918671219935, 5428.701421421821, 5433.945660543383, 5434.592405569579, 3688.6495782900715, 5352.625316227865, 5372.389595157396, 5438.211459338394, 5949.25634295713, 4164.333706606943, 5895.557833162508, 6190.78332258371, 6193.009118541035, 6194.597351172182, 5917.704114794243, 6064.9090263646085, 6162.22972128484, 6172.259328880518, 6175.197224360804, 5945.821625351246, 5984.611000284967, 5985.4912332451795, 5992.0, 6009.399060094, 13368.933155334162, 13640.386182621805, 23243.53512929732, 25525.361865953313, 26289.474210515873, 2120.925767598142, 2149.6564647095565, 2282.9543409131725, 2404.6393041043807, 2487.601983682602, 9698.70903872885, 9704.90295097047, 9930.957249488773, 10065.899341006569, 10288.794224115476, 1725.239933210122, 1793.103448275862, 1808.7467754514378, 1812.7009043507805, 1852.5831687870257, 106894.46552767264, 107364.70541178343, 108174.91825081805, 111861.76276474506, 114109.0, 11612.593559225556, 11729.530818767154, 11741.589044383405, 11746.412679365998, 11866.821997670042, 15206.0, 15273.770893436607, 15289.770653440208, 15308.081515109128, 15332.616684582974, 8410.873836892451, 8785.604647790864, 8873.112688731204, 8875.600597973107, 8978.955105224431, 8812.118788121137, 8824.382293239412, 8848.557572121368, 8922.241609463268, 8945.59744811485, 4639.234526303189, 4697.436307643105, 4699.271612900006, 4723.527647235318, 4804.087223427608, 1749.0938629702323, 1954.7458830351964, 1988.9403317900485, 1990.3829812758092, 1994.042859427454, 858.3733874271817, 925.9722208333761, 962.0, 1013.5489707080426, 1075.58589942871, 15499.690006199813, 18690.81309186918, 20384.592308153755, 20610.79389206119, 20664.400203882786, 932.7827185522958, 938.9436633801994, 957.2772556719772, 962.1773383756927, 1002.9749256268651, 10339.586416543405, 10729.731756706145, 10743.946280268548, 10814.621488247874, 10893.618723344645, 608514.0, 609635.0, 610905.0, 625159.0, 627080.0, 104197.47901260445, 117490.0, 122038.0, 122271.0, 123106.38446807709, 1003.1523362758458, 1034.8447732840134, 1044.962323469238, 1046.080442388211, 1051.2431049644244, 17308.394206202724, 17386.13069346553, 17694.911525442807, 17744.3789467368, 17746.5563360917, 50473.0, 50520.736981575756, 51030.48969510257, 51450.970980580925, 52324.47675523196, 70203.6489817568, 73140.26859731547, 73348.0, 74475.51048979099, 75475.0, 7813.804654883674, 7842.215778422112, 7859.17478664732, 7979.84040319202, 8046.959765201136, 11031.282966607145, 11767.588134415255, 12165.878341216829, 12298.20061695987, 12330.383480826102, 10092.495375231063, 10283.177345812466, 10583.047525722723, 10590.629327973484, 10748.731281717706, 10015.399076055168, 10207.642732504624, 10225.642102526375, 10534.315269507457, 10647.254692171473, 10273.589056437957, 10559.52482138321, 10660.413677247825, 10904.127669786556, 10918.50866710984, 7876.56678882667, 7886.093099293655, 7886.487378320391, 7957.920420795949, 8020.278174964281, 10225.43760093202, 10397.272190946562, 10643.201759868196, 10645.0951669109, 10662.680119596425], "value": 9321.847422285433, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1256.7934782608695, 1384.1487485196608, 1539.4688832352842, 1554.5724925645445, 1933.5552822850752, 7205.351518363346, 7280.745173918921, 8146.918530814697, 8814.647414103429, 8822.206001459866, 7415.88876166858, 8688.869666955, 8753.737387878375, 8775.385722999392, 8795.076516965722, 2165.707629470025, 2231.509068005036, 2440.3045132137345, 2468.827182097254, 2483.2301986384186, 2446.143849652616, 2511.660925775015, 2513.49730053989, 2514.710808257046, 2514.924552263435, 2414.163358054288, 2459.114718701266, 2459.434330104077, 2459.8401103928186, 2475.9504809903883, 2154.547545015533, 2381.416552944522, 2393.018862266471, 2395.928122156329, 2398.9520209595885, 2429.3197904586746, 2464.6795916530914, 2468.9135880244285, 2471.5056988602173, 2492.763187497185, 8632.0, 8718.564071796385, 8729.301655867515, 8731.432456890281, 8737.344699147527, 5296.470352964711, 5390.272313237702, 5405.864853378657, 5408.891822163535, 5454.754536045887, 3414.726821854246, 3452.154222215548, 3457.4122399192197, 3576.481410195533, 3577.964220357789, 1061.9150467962545, 1072.1369297715337, 1343.5969209237287, 1624.5694890853933, 1640.5406486183767, 11446.713832154262, 11689.473973671205, 11704.882951170464, 11720.238184517979, 11734.0, 4735.052989402134, 4804.73573953429, 4811.662718762556, 4868.586170175575, 4886.926696099561, 10258.487075646188, 10259.0, 10291.639792607222, 10295.691129266133, 10327.431991240404, 4819.277108433693, 5495.972520137374, 5517.862053448696, 5522.30971128609, 5529.889402211933, 4391.209582275157, 4493.640508759291, 4512.751798651106, 5008.248762685626, 5008.574271186917, 3581.6955558777795, 3637.7817330959697, 3722.739408241451, 3855.652991230803, 3867.8839634811, 1253.3921048291572, 1276.125853790138, 1352.864713528659, 1358.6739182596107, 1361.319340329835, 896.9372143949861, 911.8103751916188, 918.4213945214606, 919.0763282900775, 962.2398305338844, 5617.606767526234, 5772.131163914928, 5829.795957141479, 5880.411958804086, 6002.579819412599, 933.3513208320152, 979.5640939781871, 980.4019548075668, 980.8185485685171, 982.3741707474142, 3667.44988251759, 3809.447630093621, 3869.052082239813, 3897.415387691812, 3926.7447615904875, 10476.319039262424, 10839.295445795999, 10873.07578855806, 10915.072218861485, 10928.344299342065, 10236.48817559119, 10617.256792024638, 10781.9460902695, 10783.568657253621, 10821.513031913582, 8230.176982301842, 8288.590939540292, 8459.450135741157, 8469.237768600855, 8475.915240847637, 4855.0, 5551.195076713934, 5663.8867222656145, 5689.089745640677, 5899.144624029406, 9455.858162127712, 9628.566714497725, 9820.901790982, 9827.852582211415, 9851.605935762489, 9811.362261452983, 9817.361871478332, 9970.55132519024, 9980.750481238027, 10057.145142662663, 7431.8142046449275, 7492.518971090077, 7641.350485208597, 7646.885296720665, 7664.73173438927, 7124.643767811485, 8238.09380968105, 8283.668653253802, 8360.205780450848, 8363.247307742333, 6220.409061139185, 7354.866327196161, 7414.332710056121, 7428.145763237297, 7554.811129721801, 7209.459290553124, 7230.49386542958, 7270.127584689871, 7358.969744235918, 7402.6668799903055, 6817.522773405814, 6820.045193672989, 6866.416354609714, 6872.312768723088, 6895.103636527219, 4754.4532378776885, 4853.563179313879, 4899.632527560376, 4935.988122434964, 4942.975285123552, 4838.225883858566, 4883.145449546386, 4953.504649535162, 4997.550220480174, 5162.948370516397], "value": 4607.252678670291, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [317.72040604268227, 320.2633941933552, 330.89576783313254, 331.4349034895503, 333.3682671337816, 5446.91829622555, 6649.833754156149, 6674.966625166873, 6676.76631317904, 6692.996050592414, 6386.361363863612, 6629.436497897685, 6653.068570400148, 6662.967240077784, 6666.966665166679, 4826.638002149844, 4860.927086093711, 4869.366982292315, 4870.245112007645, 4875.244337127733, 4869.147899117658, 4874.244492103712, 4883.609311255091, 4886.828960986366, 4890.828820991266, 4818.373611430509, 4825.517448255181, 4828.7102773833685, 4829.22732362822, 4837.984023355082, 4812.157872372339, 4817.879553011167, 4822.107910036637, 4838.225883858601, 4838.733869637169, 3926.725129240954, 4065.166640838621, 4096.160287141129, 4096.180763847243, 4098.508179018537, 6322.178116844781, 6326.240851097852, 6335.5248356372995, 6392.520560957947, 6395.328490508523, 5638.971805140989, 5639.661620302795, 5653.236813030229, 5656.717164141776, 5668.404817494145, 7061.752838650674, 7075.257098004687, 7081.964590177067, 7089.475762711042, 7102.183248926336, 4070.738071197938, 4385.101054283864, 4583.679142460029, 4586.289125185602, 4591.517890621503, 7106.0, 7383.0, 7443.144038435595, 7454.217307182722, 7473.626318684099, 4128.876133715993, 4584.701994370355, 4610.884727881763, 4638.930416043762, 4640.95359046412, 3980.661643760313, 4520.711597194783, 4729.952700472952, 4733.715977041388, 4733.83431579893, 5507.614466987273, 5610.074337734228, 5623.10030395143, 5626.2967129108865, 5633.915491267634, 7087.787366379017, 7115.146182458035, 7124.92875071253, 7128.607926564089, 7136.393406560501, 1563.9452619158276, 1603.6712473942814, 1634.811996620404, 1640.491447651232, 1642.5729310379388, 866.9956650216709, 1017.7811770469384, 1031.128696251666, 1043.2175868098925, 1043.8642976413018, 6522.249941256721, 6565.540412171103, 6590.934090659128, 6604.471642268607, 6621.966890165519, 6562.310957349505, 6684.16447944007, 7292.744753933587, 7333.74331898381, 7344.26557344272, 6584.835379115465, 6607.603543787292, 6608.43828274602, 6609.834754131089, 6619.801405957829, 3676.0442285006093, 3877.689784817208, 3899.1032062625754, 3914.784686842194, 3917.059905622687, 6411.775587854403, 7609.695612175562, 7624.580648064299, 7634.198409167068, 7684.654190561439, 5974.641521508724, 6040.879182416327, 7554.735584254525, 7561.243875612395, 7590.506617069873, 7645.00614920068, 7650.770476885702, 7660.693572257047, 7664.770056898302, 7671.347935425328, 7275.636218189175, 7438.218987006287, 7482.70069197226, 7482.812929676802, 7488.9625551871895, 5777.3933736957015, 7124.073870396815, 7201.171865235565, 7343.3390994810725, 7370.304829889263, 7215.494915356085, 7669.309762121436, 7671.53970761756, 7746.070471543451, 7816.0, 4657.928676404378, 4668.0, 4688.578027977498, 4688.671792974459, 4712.858614241578, 7419.0, 7427.2201418850245, 7448.776536703907, 7449.143348514774, 7453.888191677021, 6924.826879328057, 7114.6442677866935, 7121.465890058163, 7190.640467976685, 7210.062691850026, 5788.392218817132, 6164.722587483484, 7107.715691372494, 7130.108736407949, 7153.177384600837, 7007.124109486314, 7026.402755765817, 7034.824129396806, 7037.0, 7051.964740176266, 7010.439164866901, 7197.928020719726, 7206.963965180141, 7211.314925082109, 7214.567125972459, 5744.8947409918, 6326.9683651581445, 6782.932170678428, 6917.23910369869, 7003.6498175092065], "value": 5120.969864772237, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [684.8869936460484, 742.1057625561199, 744.5495475237482, 763.5075376382233, 779.6374685771117, 15127.0, 15266.007709498903, 15292.0, 15317.69364612707, 15352.769708454369, 13995.370208340624, 14070.577882663536, 14071.648208794786, 14073.014888957778, 14102.224377659224, 724.4349407462174, 748.2255865179541, 908.6819613135383, 935.5088578496276, 944.8204841080208, 921.4010892919622, 929.4376901974309, 931.8648795924587, 933.1275257634134, 934.4860326820256, 985.8373368394207, 989.3766926836083, 989.4014121456523, 990.5493000684708, 991.3358050106445, 774.2257742257742, 793.1711361627056, 954.4654993203828, 974.5176137811758, 982.9705108846711, 724.1527412926888, 725.629928736343, 726.9127704675473, 727.5816405566767, 728.8141523911422, 15039.548813535503, 15093.169875656835, 15130.773038404433, 15136.167510786903, 15140.394384224617, 12442.81335779955, 12535.122541422104, 12721.745565088739, 12929.612111636667, 12939.0, 10282.897171028344, 10369.792604147875, 10395.428251446167, 10621.57513699451, 10724.624638137706, 3509.7718648288037, 3541.5218945442293, 3565.607783143878, 3610.025293170829, 3784.375578029649, 80735.0, 81731.18268817355, 82353.0, 82627.5868620653, 83241.58379208126, 9343.859842102374, 9390.671326503676, 9502.334836561375, 9546.284028697737, 9606.471644059504, 17610.559736006446, 21164.259250926145, 21200.93995300229, 21219.575608488052, 21278.36164915055, 7353.889691654629, 7370.299821516947, 7525.962370188224, 7656.157822639505, 7732.994710687574, 4001.4397984282223, 4014.1971605678395, 4031.294523458383, 4084.7753373564146, 4281.850135245314, 3500.9322156742437, 3574.0, 3578.087587665129, 3580.7314451416246, 3584.301061293023, 611.3641812514935, 619.755196697311, 620.6710443464971, 632.8734253149388, 641.2753588445121, 507.23378911581364, 513.7379936232473, 586.2554555714219, 589.4429763873098, 604.2597817673314, 7055.329743674445, 7839.137694853562, 7914.722984695508, 7914.881276780853, 8062.596870156469, 517.2938938349126, 576.1674380520192, 588.1853632718716, 600.1747597054051, 602.4487593851577, 3745.6441638044344, 3771.340015497277, 3903.59012303704, 3907.3943538751537, 3933.9009110767383, 67142.98571042779, 75095.0, 82865.17134828574, 83966.0, 91655.0, 67637.32362676409, 68672.65663671649, 70381.2961870385, 71864.56270874554, 75075.24924750792, 64099.0, 64138.0, 64459.388515287494, 64996.67501662461, 65386.0, 8242.052164001216, 8248.670053197804, 8273.586320684062, 8401.62192701342, 8633.223009929137, 44467.33299000582, 45209.0, 48815.30316651655, 50386.74806625943, 50747.746261268454, 58784.706076469345, 59180.52048698817, 59369.0, 59598.51003724941, 61319.38680613315, 10663.0, 10832.729181770204, 11030.28303160292, 11033.007029367396, 11328.564358662927, 11688.064954803765, 11901.809819018028, 12031.0, 12141.39293035327, 12404.56584019555, 8611.913880861111, 9308.674196403093, 10433.113185379329, 11284.605038823602, 11893.524259029542, 10829.945850270698, 10967.396793176453, 11074.0, 11294.322340659586, 12023.27860328383, 10843.566257349617, 11099.889001110208, 11206.215564910379, 11415.657530274106, 11450.484728187084, 9514.524273786421, 9579.425234485952, 9755.707328780149, 9823.557939892866, 10136.746581335525, 8423.957880210804, 9303.209227215491, 9327.720168394959, 9787.80424391494, 9801.362911410735], "value": 6784.329001844323, "unit": "score"}], "name": "dromaeo_css", "value": 7321.522434842975, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 115.0, 121.0, 112.0, 113.0, 112.0, 113.0, 115.0, 115.0, 133.0], "value": 116.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 121.0, 154.0, 177.0, 151.0, 141.0, 118.0, 156.0, 190.0, 119.0], "value": 148.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 185.0, 184.0, 187.0, 184.0, 181.0, 185.0, 185.0, 194.0, 232.0], "value": 189.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 78.0, 76.0, 160.0, 57.0, 56.0, 60.0, 66.0, 140.0, 60.0], "value": 82.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 148.0, 78.0, 81.0, 85.0, 129.0, 79.0, 77.0, 124.0], "value": 97.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 90.0, 134.0, 112.0, 86.0, 83.0, 87.0, 85.0, 88.0, 83.0], "value": 93.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 142.0, 93.0, 93.0, 92.0, 93.0, 92.0, 93.0, 92.0, 92.0], "value": 98.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 86.0, 85.0, 83.0, 84.0, 84.0, 83.0, 83.0, 84.0], "value": 84.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 63.0, 63.0, 63.0, 64.0, 64.0, 63.0, 83.0, 57.0, 58.0], "value": 63.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 73.0, 77.0, 65.0, 71.0, 71.0, 67.0, 69.0, 68.0], "value": 69.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 81.0, 81.0, 82.0, 80.0, 81.0, 80.0, 82.0, 84.0], "value": 81.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 128.0, 129.0, 139.0, 139.0, 119.0, 144.0, 120.0, 133.0, 183.0], "value": 136.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 168.0, 171.0, 166.0, 169.0, 216.0, 160.0, 212.0, 214.0, 164.0], "value": 181.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 96.0, 94.0, 92.0, 79.0, 70.0, 74.0, 93.0, 93.0], "value": 83.0, "unit": "score"}], "name": "kraken", "value": 1524.7000000000003, "alertThreshold": 2.0}]} 12:49:42 INFO - Return code: 0 12:49:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 12:49:42 ERROR - # TBPL SUCCESS # 12:49:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:49:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:49:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:49:42 INFO - 2016-05-13 12:49:42.124 screenresolution[1680:10234] starting screenresolution argv=screenresolution get 12:49:42 INFO - 2016-05-13 12:49:42.127 screenresolution[1680:10234] Display 0: 1600x1200x32@60 12:49:42 INFO - 2016-05-13 12:49:42.133 screenresolution[1681:10237] starting screenresolution argv=screenresolution list 12:49:42 INFO - Available Modes on Display 0 12:49:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:49:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:49:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:49:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:49:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:49:42 INFO - Intel Iris: 12:49:42 INFO - Chipset Model: Intel Iris 12:49:42 INFO - Type: GPU 12:49:42 INFO - Bus: Built-In 12:49:42 INFO - VRAM (Dynamic, Max): 1536 MB 12:49:42 INFO - Vendor: Intel (0x8086) 12:49:42 INFO - Device ID: 0x0a2e 12:49:42 INFO - Revision ID: 0x0009 12:49:42 INFO - Displays: 12:49:42 INFO - Display: 12:49:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:49:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:49:42 INFO - Display Serial Number: 1600x1200 60 12:49:42 INFO - Main Display: Yes 12:49:42 INFO - Mirror: Off 12:49:42 INFO - Online: Yes 12:49:42 INFO - Rotation: Supported 12:49:42 INFO - Return code: 0 12:49:42 INFO - Running post-action listener: _resource_record_post_action 12:49:42 INFO - Running post-run listener: _resource_record_post_run 12:49:43 INFO - Total resource usage - Wall time: 1208s; CPU: 52.0%; Read bytes: 31448576; Write bytes: 657946112; Read time: 196; Write time: 1830 12:49:43 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 345713152; Write bytes: 345261568; Read time: 26553; Write time: 699 12:49:43 INFO - run-tests - Wall time: 1177s; CPU: 52.0%; Read bytes: 30494208; Write bytes: 298264576; Read time: 185; Write time: 1076 12:49:43 INFO - Running post-run listener: _upload_blobber_files 12:49:43 INFO - Blob upload gear active. 12:49:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:49:43 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 12:49:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:49:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:49:43 INFO - (blobuploader) - INFO - Open directory for files ... 12:49:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:49:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:49:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:49:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:49:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:49:44 INFO - (blobuploader) - INFO - Done attempting. 12:49:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:49:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:49:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:49:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:49:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:49:45 INFO - (blobuploader) - INFO - Done attempting. 12:49:45 INFO - (blobuploader) - INFO - Iteration through files over. 12:49:45 INFO - Return code: 0 12:49:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:49:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:49:45 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f8e12e81f69d388a69763e37caba1ed1a5c07a89cdf527c1f70b6b7bd0fedabbc6bcf0931ea89b4f3a73b05dee80ab29eec8e85c098d11a66a0d8f855da5b9b6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/266352f8e6ce73586df907526468094f178ca87237aea1f3937205a95b694f644dcc21b852885dd5d28c03b5fa848c05e0d44eccb3c8b9b6b05057b26ce94990"} 12:49:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:49:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:49:45 INFO - Contents: 12:49:45 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f8e12e81f69d388a69763e37caba1ed1a5c07a89cdf527c1f70b6b7bd0fedabbc6bcf0931ea89b4f3a73b05dee80ab29eec8e85c098d11a66a0d8f855da5b9b6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/266352f8e6ce73586df907526468094f178ca87237aea1f3937205a95b694f644dcc21b852885dd5d28c03b5fa848c05e0d44eccb3c8b9b6b05057b26ce94990"} 12:49:45 INFO - Running post-run listener: copy_logs_to_upload_dir 12:49:45 INFO - Copying logs to upload dir... 12:49:45 INFO - mkdir: /builds/slave/test/build/upload/logs 12:49:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1326.231543 ========= master_lag: 0.09 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 6 secs) (at 2016-05-13 12:49:45.728534) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-13 12:49:45.732126) ========= 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.VzFHyujtAv/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.lkbOIc2dZ2/Listeners TMPDIR=/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f8e12e81f69d388a69763e37caba1ed1a5c07a89cdf527c1f70b6b7bd0fedabbc6bcf0931ea89b4f3a73b05dee80ab29eec8e85c098d11a66a0d8f855da5b9b6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/266352f8e6ce73586df907526468094f178ca87237aea1f3937205a95b694f644dcc21b852885dd5d28c03b5fa848c05e0d44eccb3c8b9b6b05057b26ce94990"} build_url:https://queue.taskcluster.net/v1/task/AT41oYU2Te-vURtmU60Fxw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011415 build_url: 'https://queue.taskcluster.net/v1/task/AT41oYU2Te-vURtmU60Fxw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f8e12e81f69d388a69763e37caba1ed1a5c07a89cdf527c1f70b6b7bd0fedabbc6bcf0931ea89b4f3a73b05dee80ab29eec8e85c098d11a66a0d8f855da5b9b6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/266352f8e6ce73586df907526468094f178ca87237aea1f3937205a95b694f644dcc21b852885dd5d28c03b5fa848c05e0d44eccb3c8b9b6b05057b26ce94990"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-13 12:49:45.760752) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 12:49:45.761115) ========= 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.VzFHyujtAv/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.lkbOIc2dZ2/Listeners TMPDIR=/var/folders/hm/9qlv5fh54ms08d6djtv3wf9r00000w/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.004984 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 12:49:45.809943) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 12:49:45.810264) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 12:49:45.810645) ========= ========= Total master_lag: 0.51 =========