builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0194 starttime: 1464620992.1 results: success (0) buildid: 20160530035543 builduid: 865ed214ad954ae683a81bd20aaeb948 revision: 04934785596476f6dd8baccc355202aeba59af35 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 08:09:52.095571) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 08:09:52.096086) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 08:09:52.096364) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 08:09:52.118824) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 08:09:52.119238) ========= 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.XIJvsp4BeQ/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.QymtpaQV4F/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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-30 08:09:52-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.7M=0.001s 2016-05-30 08:09:52 (15.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.120970 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 08:09:52.261332) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 08:09:52.261697) ========= 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.XIJvsp4BeQ/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.QymtpaQV4F/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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.027960 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 08:09:52.309776) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-30 08:09:52.310139) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 04934785596476f6dd8baccc355202aeba59af35 --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 04934785596476f6dd8baccc355202aeba59af35 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.XIJvsp4BeQ/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.QymtpaQV4F/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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-30 08:09:52,385 truncating revision to first 12 chars 2016-05-30 08:09:52,385 Setting DEBUG logging. 2016-05-30 08:09:52,386 attempt 1/10 2016-05-30 08:09:52,386 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/049347855964?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-30 08:09:53,948 unpacking tar archive at: mozilla-aurora-049347855964/testing/mozharness/ program finished with exit code 0 elapsedTime=1.820822 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-30 08:09:54.154411) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 08:09:54.155150) ========= script_repo_revision: 04934785596476f6dd8baccc355202aeba59af35 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 08:09:54.156054) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 08:09:54.156733) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 08:09:54.173294) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-05-30 08:09:54.173614) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.XIJvsp4BeQ/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.QymtpaQV4F/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 08:09:54 INFO - MultiFileLogger online at 20160530 08:09:54 in /builds/slave/test 08:09:54 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 08:09:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:09:54 INFO - {'append_to_log': False, 08:09:54 INFO - 'base_work_dir': '/builds/slave/test', 08:09:54 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:09:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:09:54 INFO - 'branch': 'Mozilla-Aurora', 08:09:54 INFO - 'buildbot_json_path': 'buildprops.json', 08:09:54 INFO - 'config_files': ('talos/mac_config.py',), 08:09:54 INFO - 'default_actions': ('clobber', 08:09:54 INFO - 'read-buildbot-config', 08:09:54 INFO - 'download-and-extract', 08:09:54 INFO - 'populate-webroot', 08:09:54 INFO - 'create-virtualenv', 08:09:54 INFO - 'install', 08:09:54 INFO - 'run-tests'), 08:09:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:09:54 INFO - 'download_minidump_stackwalk': True, 08:09:54 INFO - 'download_symbols': 'ondemand', 08:09:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:09:54 INFO - 'tooltool.py': '/tools/tooltool.py', 08:09:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:09:54 INFO - '/tools/misc-python/virtualenv.py')}, 08:09:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:09:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:09:54 INFO - 'installer_path': 'installer.exe', 08:09:54 INFO - 'log_level': 'info', 08:09:54 INFO - 'log_name': 'talos', 08:09:54 INFO - 'log_to_console': True, 08:09:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:09:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:09:54 INFO - 'opt_config_files': (), 08:09:54 INFO - 'pip_index': False, 08:09:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:09:54 INFO - 'cmd': ('bash', 08:09:54 INFO - '-c', 08:09:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:09:54 INFO - 'enabled': True, 08:09:54 INFO - 'halt_on_failure': False, 08:09:54 INFO - 'name': 'check_screen_resolution'},), 08:09:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:09:54 INFO - 'cmd': ('bash', 08:09:54 INFO - '-c', 08:09:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:09:54 INFO - 'enabled': True, 08:09:54 INFO - 'halt_on_failure': False, 08:09:54 INFO - 'name': 'check_screen_resolution'},), 08:09:54 INFO - 'run_cmd_checks_enabled': True, 08:09:54 INFO - 'sps_profile': False, 08:09:54 INFO - 'sps_profile_interval': 0, 08:09:54 INFO - 'suite': 'dromaeojs-e10s', 08:09:54 INFO - 'system_bits': '32', 08:09:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:09:54 INFO - 'title': 't-yosemite-r7-0194', 08:09:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:09:54 INFO - 'use_talos_json': True, 08:09:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:09:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:09:54 INFO - 'work_dir': 'build'} 08:09:54 INFO - ##### 08:09:54 INFO - ##### Running clobber step. 08:09:54 INFO - ##### 08:09:54 INFO - Running pre-action listener: _resource_record_pre_action 08:09:54 INFO - Running main action method: clobber 08:09:54 INFO - rmtree: /builds/slave/test/build 08:09:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:09:56 INFO - Running post-action listener: _resource_record_post_action 08:09:56 INFO - ##### 08:09:56 INFO - ##### Running read-buildbot-config step. 08:09:56 INFO - ##### 08:09:56 INFO - Running pre-action listener: _resource_record_pre_action 08:09:56 INFO - Running main action method: read_buildbot_config 08:09:56 INFO - Using buildbot properties: 08:09:56 INFO - { 08:09:56 INFO - "project": "", 08:09:56 INFO - "product": "firefox", 08:09:56 INFO - "script_repo_revision": "production", 08:09:56 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 08:09:56 INFO - "repository": "", 08:09:56 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 08:09:56 INFO - "buildid": "20160530035543", 08:09:56 INFO - "pgo_build": "False", 08:09:56 INFO - "basedir": "/builds/slave/test", 08:09:56 INFO - "buildnumber": 21, 08:09:56 INFO - "slavename": "t-yosemite-r7-0194", 08:09:56 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 08:09:56 INFO - "platform": "yosemite_r7", 08:09:56 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:09:56 INFO - "branch": "mozilla-aurora", 08:09:56 INFO - "repo_path": "releases/mozilla-aurora", 08:09:56 INFO - "revision": "04934785596476f6dd8baccc355202aeba59af35", 08:09:56 INFO - "stage_platform": "macosx64", 08:09:56 INFO - "builduid": "865ed214ad954ae683a81bd20aaeb948", 08:09:56 INFO - "slavebuilddir": "test" 08:09:56 INFO - } 08:09:56 INFO - Found installer url https://queue.taskcluster.net/v1/task/eAhkcfpqSpmaq-FV_-pdbw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 08:09:56 INFO - Running post-action listener: _resource_record_post_action 08:09:56 INFO - ##### 08:09:56 INFO - ##### Running download-and-extract step. 08:09:56 INFO - ##### 08:09:56 INFO - Running pre-action listener: _resource_record_pre_action 08:09:56 INFO - Running main action method: download_and_extract 08:09:56 INFO - mkdir: /builds/slave/test/build/tests 08:09:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:09:56 INFO - https://queue.taskcluster.net/v1/task/eAhkcfpqSpmaq-FV_-pdbw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 08:09:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAhkcfpqSpmaq-FV_-pdbw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 08:09:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAhkcfpqSpmaq-FV_-pdbw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 08:09:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAhkcfpqSpmaq-FV_-pdbw/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 08:09:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAhkcfpqSpmaq-FV_-pdbw/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 08:09:57 INFO - Downloaded 1199 bytes. 08:09:57 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 08:09:57 INFO - Using the following test package requirements: 08:09:57 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 08:09:57 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 08:09:57 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 08:09:57 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 08:09:57 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 08:09:57 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 08:09:57 INFO - u'jsshell-mac.zip'], 08:09:57 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 08:09:57 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 08:09:57 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 08:09:57 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 08:09:57 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 08:09:57 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 08:09:57 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 08:09:57 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 08:09:57 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 08:09:57 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 08:09:57 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 08:09:57 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:09:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:09:57 INFO - https://queue.taskcluster.net/v1/task/eAhkcfpqSpmaq-FV_-pdbw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:09:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAhkcfpqSpmaq-FV_-pdbw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 08:09:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAhkcfpqSpmaq-FV_-pdbw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 08:09:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAhkcfpqSpmaq-FV_-pdbw/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 08:09:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAhkcfpqSpmaq-FV_-pdbw/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 08:09:58 INFO - Downloaded 18425494 bytes. 08:09:58 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'] 08:09:58 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 08:10:01 INFO - Return code: 0 08:10:01 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 08:10:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:01 INFO - https://queue.taskcluster.net/v1/task/eAhkcfpqSpmaq-FV_-pdbw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:10:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAhkcfpqSpmaq-FV_-pdbw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 08:10:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAhkcfpqSpmaq-FV_-pdbw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 08:10:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAhkcfpqSpmaq-FV_-pdbw/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 08:10:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAhkcfpqSpmaq-FV_-pdbw/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 08:10:03 INFO - Downloaded 18425494 bytes. 08:10:03 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'] 08:10:03 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 08:10:06 INFO - Return code: 0 08:10:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:06 INFO - https://queue.taskcluster.net/v1/task/eAhkcfpqSpmaq-FV_-pdbw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 08:10:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAhkcfpqSpmaq-FV_-pdbw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 08:10:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAhkcfpqSpmaq-FV_-pdbw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 08:10:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAhkcfpqSpmaq-FV_-pdbw/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 08:10:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAhkcfpqSpmaq-FV_-pdbw/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 08:10:07 INFO - Downloaded 10840145 bytes. 08:10:07 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'] 08:10:07 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 08:10:08 INFO - Return code: 0 08:10:08 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')]} 08:10:08 INFO - https://queue.taskcluster.net/v1/task/eAhkcfpqSpmaq-FV_-pdbw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 08:10:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAhkcfpqSpmaq-FV_-pdbw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 08:10:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAhkcfpqSpmaq-FV_-pdbw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 08:10:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAhkcfpqSpmaq-FV_-pdbw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 08:10:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAhkcfpqSpmaq-FV_-pdbw/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 08:10:11 INFO - Downloaded 114139611 bytes. 08:10:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eAhkcfpqSpmaq-FV_-pdbw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 08:10:11 INFO - mkdir: /builds/slave/test/properties 08:10:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:10:11 INFO - Writing to file /builds/slave/test/properties/build_url 08:10:11 INFO - Contents: 08:10:11 INFO - build_url:https://queue.taskcluster.net/v1/task/eAhkcfpqSpmaq-FV_-pdbw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 08:10:12 INFO - Running post-action listener: _resource_record_post_action 08:10:12 INFO - Running post-action listener: set_extra_try_arguments 08:10:12 INFO - ##### 08:10:12 INFO - ##### Running populate-webroot step. 08:10:12 INFO - ##### 08:10:12 INFO - Running pre-action listener: _resource_record_pre_action 08:10:12 INFO - Running main action method: populate_webroot 08:10:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:10:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:10:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:10:12 INFO - '--tppagecycles', 08:10:12 INFO - '7'], 08:10:12 INFO - 'tests': ['tsvgm']}}, 08:10:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:10:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:10:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:10:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:10:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:10:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:10:12 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:10:12 INFO - 'talos_options': ['--disable-e10s'], 08:10:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:10:12 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:10:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:10:12 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:10:12 INFO - 'talos_options': ['--disable-e10s'], 08:10:12 INFO - 'tests': ['damp', 'tps']}, 08:10:12 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:10:12 INFO - 'tests': ['damp', 'tps']}, 08:10:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:10:12 INFO - 'tests': ['dromaeo_dom']}, 08:10:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:10:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:10:12 INFO - 'tests': ['a11yr', 08:10:12 INFO - 'ts_paint', 08:10:12 INFO - 'tpaint', 08:10:12 INFO - 'sessionrestore', 08:10:12 INFO - 'sessionrestore_no_auto_restore', 08:10:12 INFO - 'tabpaint']}, 08:10:12 INFO - 'other-e10s': {'tests': ['a11yr', 08:10:12 INFO - 'ts_paint', 08:10:12 INFO - 'tpaint', 08:10:12 INFO - 'sessionrestore', 08:10:12 INFO - 'sessionrestore_no_auto_restore', 08:10:12 INFO - 'tabpaint']}, 08:10:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:10:12 INFO - 'ts_paint', 08:10:12 INFO - 'tpaint', 08:10:12 INFO - 'sessionrestore', 08:10:12 INFO - 'sessionrestore_no_auto_restore', 08:10:12 INFO - 'tabpaint']}, 08:10:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:10:12 INFO - 'ts_paint', 08:10:12 INFO - 'tpaint', 08:10:12 INFO - 'sessionrestore', 08:10:12 INFO - 'sessionrestore_no_auto_restore', 08:10:12 INFO - 'tabpaint']}, 08:10:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:10:12 INFO - 'tests': ['a11yr', 08:10:12 INFO - 'ts_paint', 08:10:12 INFO - 'tpaint', 08:10:12 INFO - 'sessionrestore', 08:10:12 INFO - 'sessionrestore_no_auto_restore', 08:10:12 INFO - 'tabpaint']}, 08:10:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:10:12 INFO - 'tests': ['a11yr', 08:10:12 INFO - 'ts_paint', 08:10:12 INFO - 'tpaint', 08:10:12 INFO - 'sessionrestore', 08:10:12 INFO - 'sessionrestore_no_auto_restore', 08:10:12 INFO - 'tabpaint']}, 08:10:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:10:12 INFO - 'tests': ['tsvgx', 08:10:12 INFO - 'tsvgr_opacity', 08:10:12 INFO - 'tart', 08:10:12 INFO - 'tscrollx', 08:10:12 INFO - 'cart']}, 08:10:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:10:12 INFO - 'tsvgr_opacity', 08:10:12 INFO - 'tart', 08:10:12 INFO - 'tscrollx', 08:10:12 INFO - 'cart']}, 08:10:12 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:10:12 INFO - 'talos_options': ['--disable-e10s'], 08:10:12 INFO - 'tests': ['tp5o']}, 08:10:12 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:10:12 INFO - 'tests': ['tp5o']}, 08:10:12 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:10:12 INFO - 'talos_options': ['--disable-e10s', 08:10:12 INFO - '--xperf_path', 08:10:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:10:12 INFO - 'tests': ['tp5n']}, 08:10:12 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:10:12 INFO - 'talos_options': ['--xperf_path', 08:10:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:10:12 INFO - 'tests': ['tp5n']}}, 08:10:12 INFO - 'talos.zip': {'path': '', 08:10:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:10:12 INFO - Running post-action listener: _resource_record_post_action 08:10:12 INFO - ##### 08:10:12 INFO - ##### Running create-virtualenv step. 08:10:12 INFO - ##### 08:10:12 INFO - Running pre-action listener: _resource_record_pre_action 08:10:12 INFO - Running main action method: create_virtualenv 08:10:12 INFO - Creating virtualenv /builds/slave/test/build/venv 08:10:12 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 08:10:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:10:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:10:12 INFO - Using real prefix '/tools/python27' 08:10:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:10:13 INFO - Installing distribute.............................................................................................................................................................................................done. 08:10:16 INFO - Installing pip.................done. 08:10:16 INFO - Return code: 0 08:10:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:10:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:16 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 0x1096b2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096c2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf50dd8650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109551e00>, '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 0x1093badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10954cb20>, '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.XIJvsp4BeQ/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.QymtpaQV4F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 08:10:16 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 08:10:16 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 08:10:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XIJvsp4BeQ/Render', 08:10:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:16 INFO - 'HOME': '/Users/cltbld', 08:10:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:16 INFO - 'LOGNAME': 'cltbld', 08:10:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:16 INFO - 'MOZ_NO_REMOTE': '1', 08:10:16 INFO - 'NO_EM_RESTART': '1', 08:10:16 INFO - 'PAGER': '/bin/cat', 08:10:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:16 INFO - 'PWD': '/builds/slave/test', 08:10:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:16 INFO - 'SHELL': '/bin/bash', 08:10:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QymtpaQV4F/Listeners', 08:10:16 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 08:10:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:16 INFO - 'USER': 'cltbld', 08:10:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:16 INFO - 'XPC_FLAGS': '0x0', 08:10:16 INFO - 'XPC_SERVICE_NAME': '0', 08:10:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:16 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 08:10:16 INFO - Cleaning up... 08:10:16 INFO - Return code: 0 08:10:16 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 08:10:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:16 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 0x1096b2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096c2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf50dd8650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109551e00>, '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 0x1093badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10954cb20>, '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.XIJvsp4BeQ/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.QymtpaQV4F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 08:10:16 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 08:10:16 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 08:10:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XIJvsp4BeQ/Render', 08:10:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:16 INFO - 'HOME': '/Users/cltbld', 08:10:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:16 INFO - 'LOGNAME': 'cltbld', 08:10:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:16 INFO - 'MOZ_NO_REMOTE': '1', 08:10:16 INFO - 'NO_EM_RESTART': '1', 08:10:16 INFO - 'PAGER': '/bin/cat', 08:10:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:16 INFO - 'PWD': '/builds/slave/test', 08:10:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:16 INFO - 'SHELL': '/bin/bash', 08:10:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QymtpaQV4F/Listeners', 08:10:16 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 08:10:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:16 INFO - 'USER': 'cltbld', 08:10:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:16 INFO - 'XPC_FLAGS': '0x0', 08:10:16 INFO - 'XPC_SERVICE_NAME': '0', 08:10:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:16 INFO - Downloading/unpacking psutil>=0.7.1 08:10:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:10:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:10:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:10:19 INFO - Installing collected packages: psutil 08:10:19 INFO - Running setup.py install for psutil 08:10:19 INFO - building 'psutil._psutil_osx' extension 08:10:19 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 08:10:20 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 08:10:20 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 08:10:20 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 08:10:20 INFO - building 'psutil._psutil_posix' extension 08:10:20 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 08:10:20 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:10:20 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:10:20 INFO - ^ 08:10:20 INFO - 1 warning generated. 08:10:20 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 08:10:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:10:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:10:20 INFO - Successfully installed psutil 08:10:20 INFO - Cleaning up... 08:10:21 INFO - Return code: 0 08:10:21 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 08:10:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:21 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 0x1096b2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096c2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf50dd8650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109551e00>, '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 0x1093badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10954cb20>, '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.XIJvsp4BeQ/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.QymtpaQV4F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 08:10:21 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 08:10:21 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 08:10:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XIJvsp4BeQ/Render', 08:10:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:21 INFO - 'HOME': '/Users/cltbld', 08:10:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:21 INFO - 'LOGNAME': 'cltbld', 08:10:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:21 INFO - 'MOZ_NO_REMOTE': '1', 08:10:21 INFO - 'NO_EM_RESTART': '1', 08:10:21 INFO - 'PAGER': '/bin/cat', 08:10:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:21 INFO - 'PWD': '/builds/slave/test', 08:10:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:21 INFO - 'SHELL': '/bin/bash', 08:10:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QymtpaQV4F/Listeners', 08:10:21 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 08:10:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:21 INFO - 'USER': 'cltbld', 08:10:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:21 INFO - 'XPC_FLAGS': '0x0', 08:10:21 INFO - 'XPC_SERVICE_NAME': '0', 08:10:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:21 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 08:10:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:23 INFO - Downloading mozsystemmonitor-0.0.tar.gz 08:10:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:10:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 08:10:23 INFO - Installing collected packages: mozsystemmonitor 08:10:23 INFO - Running setup.py install for mozsystemmonitor 08:10:23 INFO - Successfully installed mozsystemmonitor 08:10:23 INFO - Cleaning up... 08:10:24 INFO - Return code: 0 08:10:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:10:24 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')]} 08:10:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:24 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')]} 08:10:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:24 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 0x1096b2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096c2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf50dd8650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109551e00>, '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 0x1093badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10954cb20>, '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.XIJvsp4BeQ/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.QymtpaQV4F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 08:10:24 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 08:10:24 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 08:10:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XIJvsp4BeQ/Render', 08:10:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:24 INFO - 'HOME': '/Users/cltbld', 08:10:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:24 INFO - 'LOGNAME': 'cltbld', 08:10:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:24 INFO - 'MOZ_NO_REMOTE': '1', 08:10:24 INFO - 'NO_EM_RESTART': '1', 08:10:24 INFO - 'PAGER': '/bin/cat', 08:10:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:24 INFO - 'PWD': '/builds/slave/test', 08:10:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:24 INFO - 'SHELL': '/bin/bash', 08:10:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QymtpaQV4F/Listeners', 08:10:24 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 08:10:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:24 INFO - 'USER': 'cltbld', 08:10:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:24 INFO - 'XPC_FLAGS': '0x0', 08:10:24 INFO - 'XPC_SERVICE_NAME': '0', 08:10:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:24 INFO - Downloading/unpacking blobuploader==1.2.4 08:10:24 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 08:10:24 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 08:10:24 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 08:10:24 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 08:10:24 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 08:10:24 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 08:10:26 INFO - Downloading blobuploader-1.2.4.tar.gz 08:10:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:10:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:10: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 08:10: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 08:10: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 08:10: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 08:10: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 08:10: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 08:10:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:10:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:10:27 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 08:10:27 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 08:10:27 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 08:10:27 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 08:10:27 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 08:10:27 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 08:10:27 INFO - Downloading docopt-0.6.1.tar.gz 08:10:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:10:28 INFO - Installing collected packages: blobuploader, requests, docopt 08:10:28 INFO - Running setup.py install for blobuploader 08:10:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:10:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:10:28 INFO - Running setup.py install for requests 08:10:28 INFO - Running setup.py install for docopt 08:10:28 INFO - Successfully installed blobuploader requests docopt 08:10:28 INFO - Cleaning up... 08:10:28 INFO - Return code: 0 08:10:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:10:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:28 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 0x1096b2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096c2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf50dd8650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109551e00>, '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 0x1093badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10954cb20>, '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.XIJvsp4BeQ/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.QymtpaQV4F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 08:10:28 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 08:10:28 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 08:10:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XIJvsp4BeQ/Render', 08:10:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:28 INFO - 'HOME': '/Users/cltbld', 08:10:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:28 INFO - 'LOGNAME': 'cltbld', 08:10:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:28 INFO - 'MOZ_NO_REMOTE': '1', 08:10:28 INFO - 'NO_EM_RESTART': '1', 08:10:28 INFO - 'PAGER': '/bin/cat', 08:10:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:28 INFO - 'PWD': '/builds/slave/test', 08:10:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:28 INFO - 'SHELL': '/bin/bash', 08:10:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QymtpaQV4F/Listeners', 08:10:28 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 08:10:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:28 INFO - 'USER': 'cltbld', 08:10:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:28 INFO - 'XPC_FLAGS': '0x0', 08:10:28 INFO - 'XPC_SERVICE_NAME': '0', 08:10:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:10:29 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-kbKiAv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:10:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:10:29 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-Eh1jkQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:10:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:10:29 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-d1FebE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:10:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:10:29 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-rW3kQR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:10:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:10:29 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-uUThlB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:10:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:10:29 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-ojGbPD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:10:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:10:29 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-GYSyYx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:10:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:10:29 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-JRv3yH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:10:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:10:29 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-CyiQTP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:10:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:10:30 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-5HawXq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:10:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:10:30 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-57wbsp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:10:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:10:30 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-jDwQKi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:10:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:10:30 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-JLmOIl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:10:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:10:30 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-pDyz4n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:10:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:10:30 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-kF_bZe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:10:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:10:30 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-1GyQ3b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:10:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:10:30 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-LpBLI8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:10:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:10:30 INFO - Running setup.py install for manifestparser 08:10:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:10:31 INFO - Running setup.py install for mozcrash 08:10:31 INFO - Running setup.py install for mozdebug 08:10:31 INFO - Running setup.py install for mozdevice 08:10:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:10:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:10:31 INFO - Running setup.py install for mozfile 08:10:31 INFO - Running setup.py install for mozhttpd 08:10:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:10:31 INFO - Running setup.py install for mozinfo 08:10:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:10:31 INFO - Running setup.py install for mozInstall 08:10:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:10:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:10:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:10:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:10:32 INFO - Running setup.py install for mozleak 08:10:32 INFO - Running setup.py install for mozlog 08:10:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:10:32 INFO - Running setup.py install for moznetwork 08:10:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:10:32 INFO - Running setup.py install for mozprocess 08:10:32 INFO - Running setup.py install for mozprofile 08:10:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:10:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:10:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:10:32 INFO - Running setup.py install for mozrunner 08:10:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:10:33 INFO - Running setup.py install for mozscreenshot 08:10:33 INFO - Running setup.py install for moztest 08:10:33 INFO - Running setup.py install for mozversion 08:10:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:10:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:10:33 INFO - Cleaning up... 08:10:33 INFO - Return code: 0 08:10:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:10:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:33 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 0x1096b2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096c2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf50dd8650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109551e00>, '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 0x1093badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10954cb20>, '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.XIJvsp4BeQ/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.QymtpaQV4F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 08:10:33 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 08:10:33 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 08:10:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XIJvsp4BeQ/Render', 08:10:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:33 INFO - 'HOME': '/Users/cltbld', 08:10:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:33 INFO - 'LOGNAME': 'cltbld', 08:10:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:33 INFO - 'MOZ_NO_REMOTE': '1', 08:10:33 INFO - 'NO_EM_RESTART': '1', 08:10:33 INFO - 'PAGER': '/bin/cat', 08:10:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:33 INFO - 'PWD': '/builds/slave/test', 08:10:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:33 INFO - 'SHELL': '/bin/bash', 08:10:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QymtpaQV4F/Listeners', 08:10:33 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 08:10:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:33 INFO - 'USER': 'cltbld', 08:10:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:33 INFO - 'XPC_FLAGS': '0x0', 08:10:33 INFO - 'XPC_SERVICE_NAME': '0', 08:10:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:10:33 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-2WhtaB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:10:33 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)) 08:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:10:33 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-1TPLpW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:10:33 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)) 08:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:10:33 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-LOtNkD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:10:34 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)) 08:10:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:10:34 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-nqFzPQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:10:34 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)) 08:10:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:10:34 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-2t46Yl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:10:34 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)) 08:10:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:10:34 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-GucgRA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:10:34 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)) 08:10:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:10:34 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-OjmzC9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:10:34 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)) 08:10:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:10:34 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-ACEyH3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:10:34 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)) 08:10:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:10:34 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-xVqdaa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:10:34 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)) 08:10:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:10:34 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-n5dMqX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:10:34 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)) 08:10:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:10:34 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-IT0IN9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:10:34 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)) 08:10:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:10:34 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-LJHeDi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:10:35 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)) 08:10:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:10:35 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-xm_OT9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:10:35 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)) 08:10:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:10:35 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-f6PieY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:10:35 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)) 08:10:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:10:35 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-nVdqBG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:10:35 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)) 08:10:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:10:35 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-AYhYDd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:10:35 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)) 08:10:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:10:35 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-7EOOti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:10:35 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)) 08:10:35 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)) 08:10:35 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)) 08:10:35 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)) 08:10:35 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)) 08:10:35 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)) 08:10:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:10:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:37 INFO - Downloading blessings-1.6.tar.gz 08:10:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:10:38 INFO - Installing collected packages: blessings 08:10:38 INFO - Running setup.py install for blessings 08:10:38 INFO - Successfully installed blessings 08:10:38 INFO - Cleaning up... 08:10:38 INFO - Return code: 0 08:10:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:10:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:10:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:10:38 INFO - Reading from file tmpfile_stdout 08:10:38 INFO - Current package versions: 08:10:38 INFO - blessings == 1.6 08:10:38 INFO - blobuploader == 1.2.4 08:10:38 INFO - docopt == 0.6.1 08:10:38 INFO - manifestparser == 1.1 08:10:38 INFO - mozInstall == 1.12 08:10:38 INFO - mozcrash == 0.17 08:10:38 INFO - mozdebug == 0.1 08:10:38 INFO - mozdevice == 0.48 08:10:38 INFO - mozfile == 1.2 08:10:38 INFO - mozhttpd == 0.7 08:10:38 INFO - mozinfo == 0.9 08:10:38 INFO - mozleak == 0.1 08:10:38 INFO - mozlog == 3.1 08:10:38 INFO - moznetwork == 0.27 08:10:38 INFO - mozprocess == 0.22 08:10:38 INFO - mozprofile == 0.28 08:10:38 INFO - mozrunner == 6.11 08:10:38 INFO - mozscreenshot == 0.1 08:10:38 INFO - mozsystemmonitor == 0.0 08:10:38 INFO - moztest == 0.7 08:10:38 INFO - mozversion == 1.4 08:10:38 INFO - psutil == 3.1.1 08:10:38 INFO - requests == 1.2.3 08:10:38 INFO - wsgiref == 0.1.2 08:10:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:10:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:38 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 0x1096b2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096c2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf50dd8650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109551e00>, '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 0x1093badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10954cb20>, '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.XIJvsp4BeQ/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.QymtpaQV4F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 08:10:38 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 08:10:38 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 08:10:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XIJvsp4BeQ/Render', 08:10:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:38 INFO - 'HOME': '/Users/cltbld', 08:10:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:38 INFO - 'LOGNAME': 'cltbld', 08:10:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:38 INFO - 'MOZ_NO_REMOTE': '1', 08:10:38 INFO - 'NO_EM_RESTART': '1', 08:10:38 INFO - 'PAGER': '/bin/cat', 08:10:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:38 INFO - 'PWD': '/builds/slave/test', 08:10:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:38 INFO - 'SHELL': '/bin/bash', 08:10:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QymtpaQV4F/Listeners', 08:10:38 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 08:10:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:38 INFO - 'USER': 'cltbld', 08:10:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:38 INFO - 'XPC_FLAGS': '0x0', 08:10:38 INFO - 'XPC_SERVICE_NAME': '0', 08:10:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:38 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)) 08:10:38 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)) 08:10:38 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)) 08:10:38 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)) 08:10:38 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)) 08:10:38 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)) 08:10:38 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)) 08:10:38 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)) 08:10:38 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)) 08:10:38 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)) 08:10:38 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)) 08:10:38 INFO - Cleaning up... 08:10:38 INFO - Return code: 0 08:10:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:10:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1096b2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096c2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf50dd8650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109551e00>, '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 0x1093badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10954cb20>, '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.XIJvsp4BeQ/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.QymtpaQV4F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 08:10:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:10:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:10:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XIJvsp4BeQ/Render', 08:10:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:38 INFO - 'HOME': '/Users/cltbld', 08:10:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:38 INFO - 'LOGNAME': 'cltbld', 08:10:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:38 INFO - 'MOZ_NO_REMOTE': '1', 08:10:38 INFO - 'NO_EM_RESTART': '1', 08:10:38 INFO - 'PAGER': '/bin/cat', 08:10:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:38 INFO - 'PWD': '/builds/slave/test', 08:10:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:38 INFO - 'SHELL': '/bin/bash', 08:10:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QymtpaQV4F/Listeners', 08:10:38 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 08:10:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:38 INFO - 'USER': 'cltbld', 08:10:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:38 INFO - 'XPC_FLAGS': '0x0', 08:10:38 INFO - 'XPC_SERVICE_NAME': '0', 08:10:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:39 INFO - Downloading/unpacking jsonschema 08:10:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:10:41 INFO - Downloading/unpacking functools32 (from jsonschema) 08:10:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:42 INFO - Downloading functools32-3.2.3-2.tar.gz 08:10:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:10:42 INFO - warning: no files found matching '*.txt' 08:10:42 INFO - no previously-included directories found matching 'build' 08:10:42 INFO - no previously-included directories found matching 'dist' 08:10:42 INFO - no previously-included directories found matching '.git*' 08:10:42 INFO - Installing collected packages: jsonschema, functools32 08:10:42 INFO - Running setup.py install for functools32 08:10:42 INFO - warning: no files found matching '*.txt' 08:10:42 INFO - no previously-included directories found matching 'build' 08:10:42 INFO - no previously-included directories found matching 'dist' 08:10:42 INFO - no previously-included directories found matching '.git*' 08:10:42 INFO - Successfully installed jsonschema functools32 08:10:42 INFO - Cleaning up... 08:10:42 INFO - Return code: 0 08:10:42 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 08:10:42 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')]} 08:10:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:42 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')]} 08:10:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:42 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 0x1096b2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096c2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faf50dd8650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109551e00>, '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 0x1093badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10954cb20>, '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.XIJvsp4BeQ/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.QymtpaQV4F/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 08:10:42 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 08:10:42 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 08:10:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XIJvsp4BeQ/Render', 08:10:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:42 INFO - 'HOME': '/Users/cltbld', 08:10:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:42 INFO - 'LOGNAME': 'cltbld', 08:10:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:42 INFO - 'MOZ_NO_REMOTE': '1', 08:10:42 INFO - 'NO_EM_RESTART': '1', 08:10:42 INFO - 'PAGER': '/bin/cat', 08:10:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:42 INFO - 'PWD': '/builds/slave/test', 08:10:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:42 INFO - 'SHELL': '/bin/bash', 08:10:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QymtpaQV4F/Listeners', 08:10:42 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 08:10:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:42 INFO - 'USER': 'cltbld', 08:10:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:42 INFO - 'XPC_FLAGS': '0x0', 08:10:42 INFO - 'XPC_SERVICE_NAME': '0', 08:10:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:42 INFO - Downloading/unpacking flake8 08:10:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:45 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:10:45 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:10:45 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 08:10:45 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 08:10:45 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 08:10:45 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 08:10:45 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 08:10:45 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 08:10:45 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:10:45 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:10:45 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 08:10:45 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 08:10:45 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 08:10:45 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 08:10:45 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 08:10:45 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 08:10:45 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:10:45 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 08:10:45 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 08:10:45 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 08:10:45 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 08:10:45 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 08:10:45 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 08:10:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 08:10:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:10:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:10:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:10:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:10:46 INFO - no previously-included directories found matching 'docs/_build' 08:10:46 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:10:46 INFO - Running setup.py install for pep8 08:10:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:10:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:10:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:10:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:10:46 INFO - no previously-included directories found matching 'docs/_build' 08:10:46 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 08:10:46 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:10:46 INFO - Cleaning up... 08:10:46 INFO - Return code: 0 08:10:46 INFO - Running post-action listener: _resource_record_post_action 08:10:46 INFO - Running post-action listener: _start_resource_monitoring 08:10:46 INFO - Starting resource monitoring. 08:10:46 INFO - ##### 08:10:46 INFO - ##### Running install step. 08:10:46 INFO - ##### 08:10:46 INFO - Running pre-action listener: _resource_record_pre_action 08:10:46 INFO - Running main action method: install 08:10:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:10:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:10:47 INFO - Reading from file tmpfile_stdout 08:10:47 INFO - Detecting whether we're running mozinstall >=1.0... 08:10:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:10:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:10:47 INFO - Reading from file tmpfile_stdout 08:10:47 INFO - Output received: 08:10:47 INFO - Usage: mozinstall [options] installer 08:10:47 INFO - Options: 08:10:47 INFO - -h, --help show this help message and exit 08:10:47 INFO - -d DEST, --destination=DEST 08:10:47 INFO - Directory to install application into. [default: 08:10:47 INFO - "/builds/slave/test"] 08:10:47 INFO - --app=APP Application being installed. [default: firefox] 08:10:47 INFO - mkdir: /builds/slave/test/build/application 08:10:47 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'] 08:10:47 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 08:11:19 INFO - Reading from file tmpfile_stdout 08:11:19 INFO - Output received: 08:11:19 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:11:19 INFO - Running post-action listener: _resource_record_post_action 08:11:19 INFO - ##### 08:11:19 INFO - ##### Running run-tests step. 08:11:19 INFO - ##### 08:11:19 INFO - Running pre-action listener: _resource_record_pre_action 08:11:19 INFO - Running main action method: run_tests 08:11:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:11:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:11:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:11:19 INFO - 2016-05-30 08:11:19.161 screenresolution[1639:7434] starting screenresolution argv=screenresolution get 08:11:19 INFO - 2016-05-30 08:11:19.163 screenresolution[1639:7434] Display 0: 1600x1200x32@60 08:11:19 INFO - 2016-05-30 08:11:19.168 screenresolution[1640:7437] starting screenresolution argv=screenresolution list 08:11:19 INFO - Available Modes on Display 0 08:11:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:11:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:11:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:11:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:11:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:11:19 INFO - Intel Iris: 08:11:19 INFO - Chipset Model: Intel Iris 08:11:19 INFO - Type: GPU 08:11:19 INFO - Bus: Built-In 08:11:19 INFO - VRAM (Dynamic, Max): 1536 MB 08:11:19 INFO - Vendor: Intel (0x8086) 08:11:19 INFO - Device ID: 0x0a2e 08:11:19 INFO - Revision ID: 0x0009 08:11:19 INFO - Displays: 08:11:19 INFO - Display: 08:11:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:11:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:11:19 INFO - Display Serial Number: 1600x1200 60 08:11:19 INFO - Main Display: Yes 08:11:19 INFO - Mirror: Off 08:11:19 INFO - Online: Yes 08:11:19 INFO - Rotation: Supported 08:11:19 INFO - Return code: 0 08:11:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:11:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:11:19 INFO - Python 2.7.3 08:11:19 INFO - Return code: 0 08:11:19 INFO - grabbing minidump binary from tooltool 08:11:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:11:19 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 0x109551e00>, '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 0x1093badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10954cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:11:19 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 08:11:19 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 08:11:19 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:11:19 INFO - Return code: 0 08:11:19 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:11:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:11:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:11:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:11:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:11:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 08:11:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 08:11:20 INFO - Return code: 0 08:11:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eAhkcfpqSpmaq-FV_-pdbw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0194', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 08:11:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/eAhkcfpqSpmaq-FV_-pdbw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0194 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 08:11:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.XIJvsp4BeQ/Render', 08:11:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:11:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:11:20 INFO - 'HOME': '/Users/cltbld', 08:11:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:11:20 INFO - 'LOGNAME': 'cltbld', 08:11:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:11:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:11:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:11:20 INFO - 'MOZ_NO_REMOTE': '1', 08:11:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:11:20 INFO - 'NO_EM_RESTART': '1', 08:11:20 INFO - 'PAGER': '/bin/cat', 08:11:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:11:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:11:20 INFO - 'PWD': '/builds/slave/test', 08:11:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:11:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:11:20 INFO - 'SHELL': '/bin/bash', 08:11:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QymtpaQV4F/Listeners', 08:11:20 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 08:11:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:11:20 INFO - 'USER': 'cltbld', 08:11:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:11:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:11:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:11:20 INFO - 'XPC_FLAGS': '0x0', 08:11:20 INFO - 'XPC_SERVICE_NAME': '0', 08:11:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:11:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eAhkcfpqSpmaq-FV_-pdbw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0194', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 08:11:20 INFO - mozversion application_buildid: 20160530035543 08:11:20 INFO - mozversion application_changeset: 04934785596476f6dd8baccc355202aeba59af35 08:11:20 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:11:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:11:20 INFO - mozversion application_name: Firefox 08:11:20 INFO - mozversion application_remotingname: firefox-dev 08:11:20 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:11:20 INFO - mozversion application_vendor: Mozilla 08:11:20 INFO - mozversion application_version: 48.0a2 08:11:20 INFO - mozversion platform_buildid: 20160530035543 08:11:20 INFO - mozversion platform_changeset: 04934785596476f6dd8baccc355202aeba59af35 08:11:20 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:11:20 INFO - mozversion platform_version: 48.0a2 08:11:20 INFO - using testdate: 1464621080 08:11:20 INFO - actual date: 1464621080 08:11:20 INFO - starting webserver on 'localhost:49275' 08:11:20 INFO - SUITE-START | Running 2 tests 08:11:20 INFO - TEST-START | dromaeo_css 08:11:20 INFO - operating with platform_type : mac_ 08:11:20 INFO - Initialising browser for dromaeo_css test... 08:11:20 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/tmpB5ZU2e/profile http://localhost:49275/getInfo.html) 08:11:23 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 08:11:23 INFO - PROCESS | 1658 | colorDepth:24 08:11:23 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 08:11:23 INFO - PROCESS | 1658 | __metrics 08:11:23 ERROR - PROCESS | 1658 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 08:11:24 INFO - TEST-INFO | 1658: exit 0 08:11:24 INFO - Browser initialized. 08:11:24 INFO - Running cycle 1/1 for dromaeo_css test... 08:11:24 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/tmpB5ZU2e/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:11:36 INFO - PROCESS | 1664 | RSS: Main: 221462528 08:11:36 INFO - PROCESS | 1664 | 08:12:26 INFO - PROCESS | 1664 | 1464621146037 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] 08:14:37 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 08:14:38 INFO - PROCESS | 1664 | RSS: Main: 233144320 08:14:38 INFO - PROCESS | 1664 | 08:17:40 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 08:17:40 INFO - PROCESS | 1664 | RSS: Main: 243552256 08:17:40 INFO - PROCESS | 1664 | 08:20:42 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 08:20:43 INFO - PROCESS | 1664 | RSS: Main: 246276096 08:20:43 INFO - PROCESS | 1664 | 08:23:44 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 08:23:45 INFO - PROCESS | 1664 | RSS: Main: 247242752 08:23:45 INFO - PROCESS | 1664 | 08:26:48 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 08:26:48 INFO - PROCESS | 1664 | RSS: Main: 250052608 08:26:48 INFO - PROCESS | 1664 | 08:29:51 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 08:29:51 INFO - PROCESS | 1664 | RSS: Main: 251322368 08:29:51 INFO - PROCESS | 1664 | 08:29:51 INFO - PROCESS | 1664 | __start_tp_report 08:29:51 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 08:29:51 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 08:29:51 INFO - PROCESS | 1664 | |i|pagename|runs| 08:29:51 INFO - PROCESS | 1664 | |0;dojo.html;3484;3532.08165876872;3549.041758725146;3562.9821850890744;3589.300086483135;5779.162021506879;5779.76880924763;5795.536357091433;5799.420057994198;5817.098349755784;5801.709914504279;5802.158686990384;5808.390119037493;5808.767649294024;5845.357010728827;5208.010478009185;5237.7904883804795;5264.341957255343;5299.28459657947;5306.177542480922;5199.662021968579;5248.29148065012;5302.231176479428;5330.973345133289;5458.6724796512135;5279.524842764151;5284.5772338212855;5292.179712144604;5306.124489459235;5374.4894235047805;4583.175028494869;4934.80260789568;5355.812546560852;5362;5384;5216.139337009389;5293.100172970604;5316.12283973144;5323.946760532383;5335.386430560497;5570.136628822539;5688.11834165705;5701.486866182022;5743.942560574383;5796.42035796417;5348.973255133738;6180.412860778219;6246.593971391845;6254.124422580842;6308.022256550242;6273.309935907047;6274.905876411857;6287.9056814147825;6292.1820163378925;6299.181106456177;5752.395998420147;5899.734511946973;5942.37605051472;5945.970270148665;5965.463108320272;6259.561830671901;6304.085907543381;6326.873462530723;6334.873302533923;6343.365663433619;6246.906296405557;6250.218722659668;6263.498920086382;6271.090691849657;6280.309165991737;6248.062790581358;6280.340564240689;6283.403076707706;6328.968355158195;6329.936700633027;5371.543418809445;5713.914291285634;5792.623479473821;6042.395760423922;6285.214348206474;6145.0950205435565;6318.968405157945;6323.683815809191;6345.524085693499;6352.078948552434;4670.859874203779;4759.452662943806;4998.175301075355;5028.270900719375;5041.495850415003;3822.808859557011;4544.863654090383;4567.680262381601;4583.954160458353;4592.6555508337005;5831.708414579254;5933.554983376178;5952.285725712942;6005.189299444606;6033.5173186145;6184.474319682788;6246.562740608205;6254.781082662085;6312.621242725451;6355.586886852339;6011.519078473799;6014.849628759316;6041.788537401258;6044.214252147192;6051.920685884305;5430.42980487051;5506.669599824023;5569.610127291051;5590.832275031756;5601.467860553241;6461.0631458438265;6515.153030106056;6527.314631963575;6541.6075035498025;6549.803505894831;6308.52686048539;6329.8734025320155;6335.208098987627;6338.334474880071;6428.260750013808;6365.554411191358;6426.550141490238;6509;6518.967405162944;6546.967265163644;6247.437730604267;6263.96868015657;6267.561270711007;6269.435750782452;6276.932921403281;6285.779997700059;6305.274893387319;6311.337309582611;6328.367163283635;6349.301576826638;5987.730552125252;6055.364186760326;6112.0171844544475;6212.968935155295;6238.875222495615;5195.636305458582;6135.53983451234;6181.721822517908;6217.658028808278;6224.190855188796;6049.697515124139;6055.394460553909;6082.695865206811;6127.816165515042;6144.938550614436;5467.917981230205;5940.990031694709;6194.101855230876;6219.440250377488;6236.844078897882;6106.297775755845;6107.348821471691;6119.2350956130485;6121.234845644294;6126.988877250665 08:29:51 INFO - PROCESS | 1664 | |1;ext.html;491.44466752569593;520.1495554767954;532.4808311895899;586.9236999190106;635.5424094651852;29570.40859182815;38977;38986.41520377197;38990;39013.804930975304;39460.21079578406;39474.8026259869;39717.80141099298;40225.59774402263;40363.79818100906;9445;9565.66520171794;9624.03759624039;9632.61469541221;9640.180584650314;9530;9774.31579789415;9779.902200977971;9793.657221997231;9854.901450985506;9785.706428807147;9830.508474576278;9843.704688859345;9857.211423086172;9876.160526355268;9770.413775173476;9781.266405019653;9802.558884850163;9848.65529706464;9867.506624668773;9673.661421850273;9721.854172187423;9722.076402741728;9754.512274386323;9778.511074446249;38378.42432363517;38455.23089538194;38580.03549911247;38662.22675546473;38737.80631096826;3249.7237734792334;3325.88359407422;3384.8984530464127;3431.811250381228;3485.8082805445692;27495;28321.57517637237;28490.430191396055;28541.28646783826;28801.855990719912;2736.097087960968;2798.930026749327;2830.872610732522;2851.6863145054026;2880.9567856482167;359186;360533;361784;361908.9999999974;362894.1855290733;66084.66957665181;66128.66935665291;66684.66630667457;66695.66652166708;66727;69699.3030069703;71509.2849071513;71511.85464436075;71744;71756;17878.91060544715;17981.730274045898;18085.36701215451;18110;18151;11485.712857178638;13392.732145357038;13573.52492662752;13616.114952528054;13666;19984.90007549953;20151.59696806085;20160.69758953617;20199.4950126248;20256.392308230777;7993.960030199928;8137.75586732399;8148;8172.713955011546;8205.917940820635;989.9950500247453;1082.3397727386289;1166.1079274355125;1180.698921774942;1209.7943349630584;30485.23786905345;30544;30699.846500767355;30858.537121943187;31302.90439834595;1096.7751610919902;1102.4818335382358;1128.8193888977723;1160.3618010094415;1166.049669519356;13669.838063764493;13717.0398072134;15166.31751571182;16442.506724798277;16599.336026559045;508147;508783;511041;514269;517574;58056;58835;59134.11298830521;59445.40554594485;59510;1394.6583087143715;1483.784851196592;1508.8415716349846;1514.606202387365;1525.046845721424;29941.550876737303;31281.374372511968;31301.217469563442;31600.841995789873;31604;54009.72995134999;55088;55295.72352138213;55898;56236.71881640566;585702;588873;589051;590788;596005;27267.318317042234;27300.45399092047;27764.861175693994;27978.860105699343;28168.01411950572;45008;45605.77197113993;47006.294905577124;47383.76308118437;47683.52316476929;38866.437110382125;38965;38985.805070974464;39203.607963919996;39335.213295734495;38328.42507362336;38476.03809904775;38674.226515470094;38973.8051309753;39012.60987390203;32765;38290;38457.4231386535;38706.8387948362;38716.6128338713;30326.393472129992;31858.522122167724;32083.197920052186;32286.35427291488;32333.838330808194;24080.63879041781;24298.14956476515;24686.01255949742;24857.25428237156;25178.496430071664 08:29:51 INFO - PROCESS | 1664 | |2;jquery.html;401.3117503481529;402.255826720567;403.33047141744674;408.4159651698071;415.323023471741;14987.775183372258;19680.015999200055;20327.1869125235;20354.89822550887;20432.795672043274;19787;19797.604047919034;19834.900825495853;20040;20119.899400503014;5575.498205161534;5646;5850.76596936122;5866.501347385477;5874.471297583216;5709.628874123189;5713.085906255;5717.514011309023;5744.080947048473;5831.2419385479825;5706.77172913083;6134.631922084667;6577.835554111161;6598.142241508597;6600.966995165041;5765.7981970631035;5828.6502809831545;6461.6446095464735;6552.57408268461;6588.407043366096;4993.325901003354;5106.795728170869;5583.637063590894;5592.356878958931;5595.18869763882;12870.613881583567;12897.032722545753;23185.53628927429;24261;24292.878535607386;2070.7515098188164;2137.2733270687886;2202.064122747826;2318.26974503031;2648.4041090754617;8005.6797728090805;8555.33171031649;9622.951885240598;9655.662051828223;9999.25005624581;1626.7315892877662;1690.2647348403484;1726.7064599018197;1753.6755700195376;1798.1728404933615;111795;117657.41171294088;117683.41158294154;119914;120493.39753301177;11919.582814601446;12019.038476921825;12034.977026952807;12066.155369124253;12101.882170620107;14860;14945.476908308157;15016.849831501764;15178.848211517743;15193.696126077417;8975.820483590292;8998;9005.459672419676;9040.502772347452;9105.180533751995;8981.191692747683;9052.094790520896;9115.772105697411;9130.81738365229;9233.261339092856;4611.515790841981;4626.329182268552;4679.485256621769;4680.602148817388;4691.7654117294;1916.4059141666128;1917.0031583576463;1986.5530255692354;2049.5695903860355;2061.29915828619;859.6819176904586;919.8666193402053;972.4602845420859;1077.3535878472853;1101.2016288191157;20263.898680506798;20373.49066273355;20399.796002040086;20866.26968056111;20901.954902254827;1006.2503434941016;1006.5219020964983;1017.5471914997755;1022.2282176956351;1031.1132426113536;9046.276297896153;10794.946025269823;10815.35107893529;10975.670729878117;11057.170712196457;588835;603385;610679;611169;611650;124017.37991309985;128373.223204956;130068.34965825299;131969;133103.66896331107;1011.4740335025746;1028.7993841200894;1030.829913064336;1041.3231399590327;1041.7864337810731;17787.199576019368;17846.929184248987;18162.909185454515;18241.726374104663;18334.816651833844;47786.28320575125;48667.513324867716;49681.50318496769;50946;51889.22166167586;74141;75449.6227518859;76690;77541.44917101739;77819;7043.788686339418;8035.598220089089;8051.074126475531;8060.355171586376;8358.707445239388;10740.46297685097;12371.75256494883;12456.875431245571;12642.494300227889;12681.30252836103;10635.255532112678;10670.19973502006;10684.732881678021;10916.727081823017;10952.288101273392;8193.959030204811;9467.100625440573;10539.15686745074;10541.4729263535;10785;10716.249862509549;10995.890041099807;11144.49849756779;11183.217174797686;11226.101911847196;7850.803729906798;7995.560244186626;8201.30288925448;8233.34133269349;8483.024452188078;8267.127001736188;11072.612458563912;11083.778324433628;11164.330140191614;11359.204855660355 08:29:51 INFO - PROCESS | 1664 | |3;mootools.html;1567.6002619332069;1599.392230952238;1601.6956778212138;1944.3194881791376;2008.6986951957215;8002.439829211958;8641.222289993899;8841.513716745576;9058.547072646366;9077.319201059921;8742.344324175681;8848.29213662907;8887.333449991243;8891.777705557364;8928.687495937644;2467.7532246775313;2483.428811373385;2496.588062969608;2507.9749202508015;2513.9371515712164;2500.0499810072147;2511.4098186926058;2515.7987361011164;2535.2014115553634;2541.745825417462;2456.668349772776;2459.827812053157;2496.1263557754824;2507.5110353481077;2522.8486290822516;2468.6790717206736;2477.6283557466436;2479.0207867892173;2499.6125600531946;2500.437401584647;2505.924822255335;2519.0427637497633;2548.120898290087;2550.6301586270074;2552.9106481273066;8960.372773905829;8973.820523589557;9012.594433250453;9040.77398065047;9047.728568142966;5395.676259424408;5522.171674248895;5582.888342233133;5596.552275817925;5599.580031497654;3587.910302242439;3669.4679271505483;3674.7978861162624;3694.722895782827;3719.497867787868;1040.625374865048;1057.8730552333768;1059.4967390489533;1061.0185578340004;1061.1775873697868;11820.29078255299;11857.407129643483;12048.156629035971;12049.518019279307;12087.818682719766;4138.482689663792;4879.4388645305535;4947.35684361038;4981.227909673934;5051.4695956924725;10279.743006424898;10349;10475.528601212964;10494;10533.894661053444;5548.472895075042;5589.301337332833;5590.748416321276;5598.440155984369;5608.803691870765;4498.932516012263;4905.435874874363;4966.90066198674;5005.499450054966;5096.490350964948;3868.5937976512623;3916.4516967624554;4124.711270211056;4198.454200953856;4260.573942605714;1357.9321033948263;1387.3895485986134;1389.5831250624772;1389.5970168651181;1398.7482253194523;927.5176908007799;933.8047299456706;943.4527973775193;951.2532661860406;956.1776871890169;5835.4748072673665;5920;5932;6082.939170608326;6176.845578860474;1025.0774303127096;1033.813913495563;1083.8699356077163;1097.9451027448595;1127.537709539085;3943;4002.0995276062654;4039.555648878621;4042.191561687674;4043.5147782265726;10635.574577016832;11059.22585419029;11090.889091109148;11102.500387482422;11128.332300062022;10706.1435085193;10865.945670271598;10870.782584348268;10924.563017479211;10957.780844383067;7049.577025378494;7217.2421895701245;7945.801354966172;8423.74728758126;8489.278411334983;5399.676019438689;5433.038993748496;5572.944270557243;5695.658260504383;6020.759169633341;7764.378849692124;7994.720184793504;9553.856692149484;9671.468069256258;9751.658691945748;9307.395019323721;9706.272029597667;9732.854007189757;9874.160696340892;9945.30382873192;6878.8624227514165;7304.707811687473;7403.852402877563;7524.17234104259;7543.811404714706;8251.587420629065;8319.916800831914;8349.582520874053;8374;8385.28725058377;7346.4122870169185;7509.586972716553;7558.697652093854;7614.428917831074;7629.961850190935;7314.305141011595;7399.149097853816;7405;7489.13874904393;7496.700131994878;6879.96800479932;6897.62063086515;6922.619255940972;6928.549644273106;6949.583025018313;4791.353167322506;4893.412790465167;4959.628027897994;4962.404511458648;4979.626528010342;4918.43438004634;4925.704457732548;4931.605471562195;4949.985253023194;5220.034293655534 08:29:51 INFO - PROCESS | 1664 | |4;prototype.html;282.7441165745001;323.1017770597738;331.20015163380435;337.1302040734903;337.93072420153874;5460.808871689486;6725.260221375657;6812.727490900371;6899.758508452205;6932.4454043676515;5158.613104017204;6899.310068993098;6901.206361268443;6943.618101004443;6951.652417379123;5033.496650334965;5075.796968121271;5080.568151707091;5084.211947148198;5106.757209172165;5083.415407228161;5093.26147708582;5094.439611642716;5099.643024988234;5100.387953445592;5001.424836143854;5008.699478031312;5029.1953287473825;5036.34527511423;5046.293518907356;4023.436718859362;4057.3913912913004;4327.701689493171;4871.5372039655995;5028.597712183035;3958.920821583581;4148.43996060531;4150.522689890671;4154.480689913761;4168.7081904266715;4653.766751810773;5837.620554663976;6592.241892182413;6598.93401065988;6647.1026411434805;4811.085893680224;5846.298444186725;5850.9414905851245;5912.379200184005;5923.614965027303;5608.653923058478;7359.153697324823;7359.3744531714865;7368.3736882365065;7403.074615673041;3951.3480275754464;4484.865454036384;4536.160810250114;4710.481846996828;4714.905701885943;7622.771316860503;7704.8844267336035;7719.729809456642;7743.225677432324;7803.765887023398;4774.522547745198;4779.211430114062;4783.641226907996;4784.067106914188;4807.591354734816;4857.441394239637;4858.684185527929;4871.805127794849;4880.585150262196;4881.487443818348;5852.356240813507;5859.267591551056;5861.824145275648;5878.883012227663;5883.0881213411985;7140.750073747394;7401.444891633149;7405.037345145097;7428.62856857155;7439.107307123179;1668.6329007618303;1670.3903075377643;1691.7462380643003;1696.3129932377408;1698.9405370811962;995.0894931138073;1050.1965996013057;1055.6516349604613;1069.6470164845582;1073.5491093740648;6798.824376199667;6853.040574319599;6874.759383421556;6914.965425172842;6929.5842249465195;7690.692372305157;7696.499727517694;7713.8457230855065;7720.266574675396;7722.613869306625;6975.895361569581;6978.476614253951;6980.860382792316;7004.299570042955;7026.80544307469;3907.296686596383;4075.2664520386024;4085.652719518874;4091.754494730326;4093.0790572121036;7332.680117578887;7539.982102416213;8140.3487720982175;8189.9762529683785;8265.793355166168;6019;6427;7959.44283900133;7998.960005199937;8007.199280071946;6911.5831254593695;8224.8355032899;8225.629846656915;8237.423380363436;8237.711680091168;7399.18608953004;7464.440166987606;7657.731979380695;7707.807304817425;7824.374050076094;7563.903234030925;7599.354054905174;7604.8479030417975;7612.581308028111;7668.817440762021;8147.551884646165;8153.143919888326;8169.959150204211;8172.550509721785;8174.754757357289;4295.978520107379;4763.0235801661265;4776.164171269945;4782.641301902441;4786.760661966958;6092.969535152473;7410.221926697619;7625.466217364731;7626.199249078989;7650.4644674872225;7330.670119844726;7389.926100738924;7403.370713489414;7405.925940740523;7409.925900740924;7362.889556656547;7366.7053317866685;7378.483506154517;7379.963100184465;7401.925980740124;6475.7733479328;6556.213254409501;7066.858662826818;7197.568145911262;7208.243134471015;7267.454940879561;7377.704891804267;7387.113546374255;7388.371988381048;7423;6924.549904256207;7019.543729657556;7175.425965922609;7178.964105179441;7311.4150867929375 08:29:51 INFO - PROCESS | 1664 | |5;yui.html;695.6069820551389;737.4174402222245;749.1422321441946;751.0949306086167;764.7782143178479;15231.999999999985;15242.46651367201;15322.846771532282;15382.615434614141;15592;13999.30003499826;14044.29778511073;14061.296935153252;14073.64815879601;14085.084469509475;724.1238101896711;903.7424334064793;912.0423555266959;927.6845872403381;935.3078721745893;919.5769945824928;924.8982611912684;926.592299388267;927.2303987690201;935.6678379175397;964.84080126779;977.8288799460101;982.592224226945;985.1380042462845;985.3053597213947;934.7709811096257;953.4041224234853;966.6085235479607;971.7910649210419;973.2019743810079;712.7256006437507;713.4043074033189;718.9496735228536;728.3845150847526;729.7081167532976;14828.184449855253;14896.925515372353;14970.251487425694;15254.08475491463;15351.155686437241;12976.805347919788;13006.154599951067;13082.95336373088;13164.60506184816;13167.934160329138;9809.607615695435;10202.693919182362;10270.89729102707;10354.430506322149;10363.533640986097;3511.262634846685;3534.1164708822794;3647.4893514907935;3678.1356381250384;3791.6018818023986;82694.58652706759;83124.16875831225;83251;83552.16447835504;84306.89232769169;9314;9391.177833472058;9512.524373781283;9593.760155996157;9692.903070969342;20553.38339849807;21121.471963201042;21305.934703264775;21341.46646333854;21409;7287.417006639456;7343.596102214323;7352.19125896151;7405.444591655647;7431.2197219292275;3384.915377115592;3409;3987.441758153861;4097.016715988201;4101.138760860253;3079.876804927823;3605.7656252343513;3622.673959343672;3675.5221821163077;3696.242270334575;647.1296106736526;654.3587284461255;655.3708439897656;664.2394458345265;672.2370109925172;470.5647276269437;607.6354187487473;613.2457077794338;616.14233087242;618.7524990004034;7716.344110750537;8265.752027439186;8291;8403.117672644285;8659.350548708871;595.7200115945499;598.7156100852061;610.1488423649073;616.1466369078769;619.9225096862892;3750.456183853326;3773.6981041516615;3781.205946751158;3934.2328245991757;4019.738716983445;83380;88667.66998495028;89020.6646900297;89507.55246223728;89726.55136724406;76810;77101;77159.61420192863;78312;78629.60685196651;64327.78361081927;66736.66631666811;69743.65128174327;70540;74182;8225;8500.404971652173;8569.442986205877;8625.05124436324;8691.919519633557;35138.64861351456;41357.37963930483;48702.26946595733;48790.5120948786;50606.74696626641;52280.69298267421;53457.465425345246;63330.68334658297;64650.676746615965;65213.67393163004;10805.351678899291;10832;10856.945715271373;11156.107511398906;11499.942500287445;11622.360770157722;11633.185677002528;11688.415579220835;11793.05655547537;11814.442186648095;8925.73222803317;11031.393273370042;11095.778084438105;11350.148738844784;11462.369569673749;10932.836007459735;11192.88807111951;11217.383043932661;11300.090769757486;11616.535338586362;9262.16640502358;11037.724056898642;11043;11044.668659940215;11593.362365070003;7916.327112195528;8179.591020449073;9587.233021358135;9638.373505722106;9810;9502.904970950478;9582;9650.420974741539;9703.563339649592;9766.12104910533 08:29:51 INFO - PROCESS | 1664 | __end_tp_report 08:29:51 INFO - PROCESS | 1664 | __start_cc_report 08:29:51 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,421 08:29:51 INFO - PROCESS | 1664 | __end_cc_report 08:29:51 INFO - PROCESS | 1664 | __startTimestamp1464622191358__endTimestamp 08:29:51 INFO - PROCESS | 1664 | 08:29:51 INFO - PROCESS | 1664 | ------- Summary: start ------- 08:29:51 INFO - PROCESS | 1664 | Number of tests: 6 08:29:51 INFO - PROCESS | 1664 | 08:29:51 INFO - PROCESS | 1664 | [#0] dojo.html Cycles:165 Average:5811.64 Median:6044.21 stddev:649.05 (10.7%) stddev-sans-first:624.82 08:29:51 INFO - PROCESS | 1664 | Values: 3484.0 3532.1 3549.0 3563.0 3589.3 5779.2 5779.8 5795.5 5799.4 5817.1 5801.7 5802.2 5808.4 5808.8 5845.4 5208.0 5237.8 5264.3 5299.3 5306.2 5199.7 5248.3 5302.2 5331.0 5458.7 5279.5 5284.6 5292.2 5306.1 5374.5 4583.2 4934.8 5355.8 5362.0 5384.0 5216.1 5293.1 5316.1 5323.9 5335.4 5570.1 5688.1 5701.5 5743.9 5796.4 5349.0 6180.4 6246.6 6254.1 6308.0 6273.3 6274.9 6287.9 6292.2 6299.2 5752.4 5899.7 5942.4 5946.0 5965.5 6259.6 6304.1 6326.9 6334.9 6343.4 6246.9 6250.2 6263.5 6271.1 6280.3 6248.1 6280.3 6283.4 6329.0 6329.9 5371.5 5713.9 5792.6 6042.4 6285.2 6145.1 6319.0 6323.7 6345.5 6352.1 4670.9 4759.5 4998.2 5028.3 5041.5 3822.8 4544.9 4567.7 4584.0 4592.7 5831.7 5933.6 5952.3 6005.2 6033.5 6184.5 6246.6 6254.8 6312.6 6355.6 6011.5 6014.8 6041.8 6044.2 6051.9 5430.4 5506.7 5569.6 5590.8 5601.5 6461.1 6515.2 6527.3 6541.6 6549.8 6308.5 6329.9 6335.2 6338.3 6428.3 6365.6 6426.6 6509.0 6519.0 6547.0 6247.4 6264.0 6267.6 6269.4 6276.9 6285.8 6305.3 6311.3 6328.4 6349.3 5987.7 6055.4 6112.0 6213.0 6238.9 5195.6 6135.5 6181.7 6217.7 6224.2 6049.7 6055.4 6082.7 6127.8 6144.9 5467.9 5941.0 6194.1 6219.4 6236.8 6106.3 6107.3 6119.2 6121.2 6127.0 08:29:51 INFO - PROCESS | 1664 | 08:29:51 INFO - PROCESS | 1664 | [#1] ext.html Cycles:180 Average:63907.34 Median:38711.73 stddev:132415.23 (342.1%) stddev-sans-first:132701.09 08:29:51 INFO - PROCESS | 1664 | Values: 491.4 520.1 532.5 586.9 635.5 29570.4 38977.0 38986.4 38990.0 39013.8 39460.2 39474.8 39717.8 40225.6 40363.8 9445.0 9565.7 9624.0 9632.6 9640.2 9530.0 9774.3 9779.9 9793.7 9854.9 9785.7 9830.5 9843.7 9857.2 9876.2 9770.4 9781.3 9802.6 9848.7 9867.5 9673.7 9721.9 9722.1 9754.5 9778.5 38378.4 38455.2 38580.0 38662.2 38737.8 3249.7 3325.9 3384.9 3431.8 3485.8 27495.0 28321.6 28490.4 28541.3 28801.9 2736.1 2798.9 2830.9 2851.7 2881.0 359186.0 360533.0 361784.0 361909.0 362894.2 66084.7 66128.7 66684.7 66695.7 66727.0 69699.3 71509.3 71511.9 71744.0 71756.0 17878.9 17981.7 18085.4 18110.0 18151.0 11485.7 13392.7 13573.5 13616.1 13666.0 19984.9 20151.6 20160.7 20199.5 20256.4 7994.0 8137.8 8148.0 8172.7 8205.9 990.0 1082.3 1166.1 1180.7 1209.8 30485.2 30544.0 30699.8 30858.5 31302.9 1096.8 1102.5 1128.8 1160.4 1166.0 13669.8 13717.0 15166.3 16442.5 16599.3 508147.0 508783.0 511041.0 514269.0 517574.0 58056.0 58835.0 59134.1 59445.4 59510.0 1394.7 1483.8 1508.8 1514.6 1525.0 29941.6 31281.4 31301.2 31600.8 31604.0 54009.7 55088.0 55295.7 55898.0 56236.7 585702.0 588873.0 589051.0 590788.0 596005.0 27267.3 27300.5 27764.9 27978.9 28168.0 45008.0 45605.8 47006.3 47383.8 47683.5 38866.4 38965.0 38985.8 39203.6 39335.2 38328.4 38476.0 38674.2 38973.8 39012.6 32765.0 38290.0 38457.4 38706.8 38716.6 30326.4 31858.5 32083.2 32286.4 32333.8 24080.6 24298.1 24686.0 24857.3 25178.5 08:29:51 INFO - PROCESS | 1664 | 08:29:51 INFO - PROCESS | 1664 | [#2] jquery.html Cycles:180 Average:34846.49 Median:11519.61 stddev:100982.44 (876.6%) stddev-sans-first:101232.60 08:29:51 INFO - PROCESS | 1664 | Values: 401.3 402.3 403.3 408.4 415.3 14987.8 19680.0 20327.2 20354.9 20432.8 19787.0 19797.6 19834.9 20040.0 20119.9 5575.5 5646.0 5850.8 5866.5 5874.5 5709.6 5713.1 5717.5 5744.1 5831.2 5706.8 6134.6 6577.8 6598.1 6601.0 5765.8 5828.7 6461.6 6552.6 6588.4 4993.3 5106.8 5583.6 5592.4 5595.2 12870.6 12897.0 23185.5 24261.0 24292.9 2070.8 2137.3 2202.1 2318.3 2648.4 8005.7 8555.3 9623.0 9655.7 9999.3 1626.7 1690.3 1726.7 1753.7 1798.2 111795.0 117657.4 117683.4 119914.0 120493.4 11919.6 12019.0 12035.0 12066.2 12101.9 14860.0 14945.5 15016.8 15178.8 15193.7 8975.8 8998.0 9005.5 9040.5 9105.2 8981.2 9052.1 9115.8 9130.8 9233.3 4611.5 4626.3 4679.5 4680.6 4691.8 1916.4 1917.0 1986.6 2049.6 2061.3 859.7 919.9 972.5 1077.4 1101.2 20263.9 20373.5 20399.8 20866.3 20902.0 1006.3 1006.5 1017.5 1022.2 1031.1 9046.3 10794.9 10815.4 10975.7 11057.2 588835.0 603385.0 610679.0 611169.0 611650.0 124017.4 128373.2 130068.3 131969.0 133103.7 1011.5 1028.8 1030.8 1041.3 1041.8 17787.2 17846.9 18162.9 18241.7 18334.8 47786.3 48667.5 49681.5 50946.0 51889.2 74141.0 75449.6 76690.0 77541.4 77819.0 7043.8 8035.6 8051.1 8060.4 8358.7 10740.5 12371.8 12456.9 12642.5 12681.3 10635.3 10670.2 10684.7 10916.7 10952.3 8194.0 9467.1 10539.2 10541.5 10785.0 10716.2 10995.9 11144.5 11183.2 11226.1 7850.8 7995.6 8201.3 8233.3 8483.0 8267.1 11072.6 11083.8 11164.3 11359.2 08:29:51 INFO - PROCESS | 1664 | 08:29:51 INFO - PROCESS | 1664 | [#3] mootools.html Cycles:180 Average:5769.51 Median:4954.81 stddev:3171.00 (64.0%) stddev-sans-first:3164.18 08:29:51 INFO - PROCESS | 1664 | Values: 1567.6 1599.4 1601.7 1944.3 2008.7 8002.4 8641.2 8841.5 9058.5 9077.3 8742.3 8848.3 8887.3 8891.8 8928.7 2467.8 2483.4 2496.6 2508.0 2513.9 2500.0 2511.4 2515.8 2535.2 2541.7 2456.7 2459.8 2496.1 2507.5 2522.8 2468.7 2477.6 2479.0 2499.6 2500.4 2505.9 2519.0 2548.1 2550.6 2552.9 8960.4 8973.8 9012.6 9040.8 9047.7 5395.7 5522.2 5582.9 5596.6 5599.6 3587.9 3669.5 3674.8 3694.7 3719.5 1040.6 1057.9 1059.5 1061.0 1061.2 11820.3 11857.4 12048.2 12049.5 12087.8 4138.5 4879.4 4947.4 4981.2 5051.5 10279.7 10349.0 10475.5 10494.0 10533.9 5548.5 5589.3 5590.7 5598.4 5608.8 4498.9 4905.4 4966.9 5005.5 5096.5 3868.6 3916.5 4124.7 4198.5 4260.6 1357.9 1387.4 1389.6 1389.6 1398.7 927.5 933.8 943.5 951.3 956.2 5835.5 5920.0 5932.0 6082.9 6176.8 1025.1 1033.8 1083.9 1097.9 1127.5 3943.0 4002.1 4039.6 4042.2 4043.5 10635.6 11059.2 11090.9 11102.5 11128.3 10706.1 10865.9 10870.8 10924.6 10957.8 7049.6 7217.2 7945.8 8423.7 8489.3 5399.7 5433.0 5572.9 5695.7 6020.8 7764.4 7994.7 9553.9 9671.5 9751.7 9307.4 9706.3 9732.9 9874.2 9945.3 6878.9 7304.7 7403.9 7524.2 7543.8 8251.6 8319.9 8349.6 8374.0 8385.3 7346.4 7509.6 7558.7 7614.4 7630.0 7314.3 7399.1 7405.0 7489.1 7496.7 6880.0 6897.6 6922.6 6928.5 6949.6 4791.4 4893.4 4959.6 4962.4 4979.6 4918.4 4925.7 4931.6 4950.0 5220.0 08:29:51 INFO - PROCESS | 1664 | 08:29:51 INFO - PROCESS | 1664 | [#4] prototype.html Cycles:180 Average:5946.65 Median:6863.90 stddev:1964.70 (28.6%) stddev-sans-first:1923.67 08:29:51 INFO - PROCESS | 1664 | Values: 282.7 323.1 331.2 337.1 337.9 5460.8 6725.3 6812.7 6899.8 6932.4 5158.6 6899.3 6901.2 6943.6 6951.7 5033.5 5075.8 5080.6 5084.2 5106.8 5083.4 5093.3 5094.4 5099.6 5100.4 5001.4 5008.7 5029.2 5036.3 5046.3 4023.4 4057.4 4327.7 4871.5 5028.6 3958.9 4148.4 4150.5 4154.5 4168.7 4653.8 5837.6 6592.2 6598.9 6647.1 4811.1 5846.3 5850.9 5912.4 5923.6 5608.7 7359.2 7359.4 7368.4 7403.1 3951.3 4484.9 4536.2 4710.5 4714.9 7622.8 7704.9 7719.7 7743.2 7803.8 4774.5 4779.2 4783.6 4784.1 4807.6 4857.4 4858.7 4871.8 4880.6 4881.5 5852.4 5859.3 5861.8 5878.9 5883.1 7140.8 7401.4 7405.0 7428.6 7439.1 1668.6 1670.4 1691.7 1696.3 1698.9 995.1 1050.2 1055.7 1069.6 1073.5 6798.8 6853.0 6874.8 6915.0 6929.6 7690.7 7696.5 7713.8 7720.3 7722.6 6975.9 6978.5 6980.9 7004.3 7026.8 3907.3 4075.3 4085.7 4091.8 4093.1 7332.7 7540.0 8140.3 8190.0 8265.8 6019.0 6427.0 7959.4 7999.0 8007.2 6911.6 8224.8 8225.6 8237.4 8237.7 7399.2 7464.4 7657.7 7707.8 7824.4 7563.9 7599.4 7604.8 7612.6 7668.8 8147.6 8153.1 8170.0 8172.6 8174.8 4296.0 4763.0 4776.2 4782.6 4786.8 6093.0 7410.2 7625.5 7626.2 7650.5 7330.7 7389.9 7403.4 7405.9 7409.9 7362.9 7366.7 7378.5 7380.0 7401.9 6475.8 6556.2 7066.9 7197.6 7208.2 7267.5 7377.7 7387.1 7388.4 7423.0 6924.5 7019.5 7175.4 7179.0 7311.4 08:29:51 INFO - PROCESS | 1664 | 08:29:51 INFO - PROCESS | 1664 | [#5] yui.html Cycles:180 Average:17819.78 Median:64489.23 stddev:24943.57 (38.7%) stddev-sans-first:24980.40 08:29:51 INFO - PROCESS | 1664 | Values: 695.6 737.4 749.1 751.1 764.8 15232.0 15242.5 15322.8 15382.6 15592.0 13999.3 14044.3 14061.3 14073.6 14085.1 724.1 903.7 912.0 927.7 935.3 919.6 924.9 926.6 927.2 935.7 964.8 977.8 982.6 985.1 985.3 934.8 953.4 966.6 971.8 973.2 712.7 713.4 718.9 728.4 729.7 14828.2 14896.9 14970.3 15254.1 15351.2 12976.8 13006.2 13083.0 13164.6 13167.9 9809.6 10202.7 10270.9 10354.4 10363.5 3511.3 3534.1 3647.5 3678.1 3791.6 82694.6 83124.2 83251.0 83552.2 84306.9 9314.0 9391.2 9512.5 9593.8 9692.9 20553.4 21121.5 21305.9 21341.5 21409.0 7287.4 7343.6 7352.2 7405.4 7431.2 3384.9 3409.0 3987.4 4097.0 4101.1 3079.9 3605.8 3622.7 3675.5 3696.2 647.1 654.4 655.4 664.2 672.2 470.6 607.6 613.2 616.1 618.8 7716.3 8265.8 8291.0 8403.1 8659.4 595.7 598.7 610.1 616.1 619.9 3750.5 3773.7 3781.2 3934.2 4019.7 83380.0 88667.7 89020.7 89507.6 89726.6 76810.0 77101.0 77159.6 78312.0 78629.6 64327.8 66736.7 69743.7 70540.0 74182.0 8225.0 8500.4 8569.4 8625.1 8691.9 35138.6 41357.4 48702.3 48790.5 50606.7 52280.7 53457.5 63330.7 64650.7 65213.7 10805.4 10832.0 10856.9 11156.1 11499.9 11622.4 11633.2 11688.4 11793.1 11814.4 8925.7 11031.4 11095.8 11350.1 11462.4 10932.8 11192.9 11217.4 11300.1 11616.5 9262.2 11037.7 11043.0 11044.7 11593.4 7916.3 8179.6 9587.2 9638.4 9810.0 9502.9 9582.0 9650.4 9703.6 9766.1 08:29:51 INFO - PROCESS | 1664 | -------- Summary: end -------- 08:29:51 INFO - PROCESS | 1664 | 08:29:51 INFO - TEST-INFO | 1664: exit 0 08:29:51 INFO - TEST-OK | dromaeo_css | took 1111158ms 08:29:51 INFO - TEST-START | kraken 08:29:51 INFO - operating with platform_type : mac_ 08:29:51 INFO - Initialising browser for kraken test... 08:29:51 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/tmp97OXwL/profile http://localhost:49275/getInfo.html) 08:29:54 INFO - PROCESS | 1673 | __metrics Screen width/height:1600/1200 08:29:54 INFO - PROCESS | 1673 | colorDepth:24 08:29:54 INFO - PROCESS | 1673 | Browser inner width/height: 1024/689 08:29:54 INFO - PROCESS | 1673 | __metrics 08:29:54 ERROR - PROCESS | 1673 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 08:29:55 INFO - TEST-INFO | 1673: exit 0 08:29:55 INFO - Browser initialized. 08:29:55 INFO - Running cycle 1/1 for kraken test... 08:29:55 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/tmp97OXwL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:30:07 INFO - PROCESS | 1676 | RSS: Main: 221290496 08:30:07 INFO - PROCESS | 1676 | 08:30:09 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 08:30:10 INFO - PROCESS | 1676 | RSS: Main: 233062400 08:30:10 INFO - PROCESS | 1676 | 08:30:13 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 08:30:14 INFO - PROCESS | 1676 | RSS: Main: 241905664 08:30:14 INFO - PROCESS | 1676 | 08:30:17 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 08:30:18 INFO - PROCESS | 1676 | RSS: Main: 245911552 08:30:18 INFO - PROCESS | 1676 | 08:30:20 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 08:30:20 INFO - PROCESS | 1676 | RSS: Main: 246198272 08:30:20 INFO - PROCESS | 1676 | 08:30:22 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:30:23 INFO - PROCESS | 1676 | RSS: Main: 247824384 08:30:23 INFO - PROCESS | 1676 | 08:30:26 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 08:30:26 INFO - PROCESS | 1676 | RSS: Main: 247545856 08:30:26 INFO - PROCESS | 1676 | 08:30:29 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 08:30:30 INFO - PROCESS | 1676 | RSS: Main: 247611392 08:30:30 INFO - PROCESS | 1676 | 08:30:33 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 08:30:33 INFO - PROCESS | 1676 | RSS: Main: 247808000 08:30:33 INFO - PROCESS | 1676 | 08:30:35 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:30:35 INFO - PROCESS | 1676 | RSS: Main: 247316480 08:30:35 INFO - PROCESS | 1676 | 08:30:37 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:30:38 INFO - PROCESS | 1676 | RSS: Main: 247488512 08:30:38 INFO - PROCESS | 1676 | 08:30:40 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:30:41 INFO - PROCESS | 1676 | RSS: Main: 247771136 08:30:41 INFO - PROCESS | 1676 | 08:30:43 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:30:44 INFO - PROCESS | 1676 | RSS: Main: 247803904 08:30:44 INFO - PROCESS | 1676 | 08:30:47 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:30:48 INFO - PROCESS | 1676 | RSS: Main: 247803904 08:30:48 INFO - PROCESS | 1676 | 08:30:49 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 08:30:50 INFO - PROCESS | 1676 | RSS: Main: 249868288 08:30:50 INFO - PROCESS | 1676 | 08:30:50 INFO - PROCESS | 1676 | __start_tp_report 08:30:50 INFO - PROCESS | 1676 | _x_x_mozilla_page_load 08:30:50 INFO - PROCESS | 1676 | _x_x_mozilla_page_load_details 08:30:50 INFO - PROCESS | 1676 | |i|pagename|runs| 08:30:50 INFO - PROCESS | 1676 | |0;ai-astar;113;119;108;116;118;118;106;116;117;116 08:30:50 INFO - PROCESS | 1676 | |1;audio-beat-detection;152;130;157;155;121;144;146;186;195;123 08:30:50 INFO - PROCESS | 1676 | |2;audio-dft;199;198;233;258;196;194;197;200;233;278 08:30:50 INFO - PROCESS | 1676 | |3;audio-fft;63;74;71;142;57;56;57;61;126;57 08:30:50 INFO - PROCESS | 1676 | |4;audio-oscillator;81;80;122;79;82;96;140;115;116;149 08:30:50 INFO - PROCESS | 1676 | |5;imaging-gaussian-blur;92;88;90;87;90;87;92;88;91;86 08:30:50 INFO - PROCESS | 1676 | |6;imaging-darkroom;93;96;95;95;95;107;95;96;106;95 08:30:50 INFO - PROCESS | 1676 | |7;imaging-desaturate;99;95;90;92;91;90;89;94;89;89 08:30:50 INFO - PROCESS | 1676 | |8;json-parse-financial;60;63;66;65;68;67;67;78;62;60 08:30:50 INFO - PROCESS | 1676 | |9;json-stringify-tinderbox;63;68;67;73;72;75;70;70;83;88 08:30:50 INFO - PROCESS | 1676 | |10;stanford-crypto-aes;81;82;84;83;86;81;84;82;86;90 08:30:50 INFO - PROCESS | 1676 | |11;stanford-crypto-ccm;131;135;145;151;132;125;125;117;172;175 08:30:50 INFO - PROCESS | 1676 | |12;stanford-crypto-pbkdf2;169;169;170;170;169;156;154;216;221;169 08:30:50 INFO - PROCESS | 1676 | |13;stanford-crypto-sha256-iterative;66;67;78;95;103;77;60;92;59;68 08:30:50 INFO - PROCESS | 1676 | __end_tp_report 08:30:50 INFO - PROCESS | 1676 | __start_cc_report 08:30:50 INFO - PROCESS | 1676 | _x_x_mozilla_cycle_collect,1099 08:30:50 INFO - PROCESS | 1676 | __end_cc_report 08:30:50 INFO - PROCESS | 1676 | __startTimestamp1464622250242__endTimestamp 08:30:50 INFO - PROCESS | 1676 | 08:30:50 INFO - PROCESS | 1676 | ------- Summary: start ------- 08:30:50 INFO - PROCESS | 1676 | Number of tests: 14 08:30:50 INFO - PROCESS | 1676 | 08:30:50 INFO - PROCESS | 1676 | [#0] ai-astar Cycles:10 Average:114.70 Median:116.50 stddev:4.40 (3.8%) stddev-sans-first:4.62 08:30:50 INFO - PROCESS | 1676 | Values: 113.0 119.0 108.0 116.0 118.0 118.0 106.0 116.0 117.0 116.0 08:30:50 INFO - PROCESS | 1676 | 08:30:50 INFO - PROCESS | 1676 | [#1] audio-beat-detection Cycles:10 Average:150.90 Median:153.50 stddev:24.52 (16.0%) stddev-sans-first:26.01 08:30:50 INFO - PROCESS | 1676 | Values: 152.0 130.0 157.0 155.0 121.0 144.0 146.0 186.0 195.0 123.0 08:30:50 INFO - PROCESS | 1676 | 08:30:50 INFO - PROCESS | 1676 | [#2] audio-dft Cycles:10 Average:218.60 Median:216.50 stddev:30.24 (14.0%) stddev-sans-first:31.24 08:30:50 INFO - PROCESS | 1676 | Values: 199.0 198.0 233.0 258.0 196.0 194.0 197.0 200.0 233.0 278.0 08:30:50 INFO - PROCESS | 1676 | 08:30:50 INFO - PROCESS | 1676 | [#3] audio-fft Cycles:10 Average:76.40 Median:59.00 stddev:31.20 (52.9%) stddev-sans-first:32.71 08:30:50 INFO - PROCESS | 1676 | Values: 63.0 74.0 71.0 142.0 57.0 56.0 57.0 61.0 126.0 57.0 08:30:50 INFO - PROCESS | 1676 | 08:30:50 INFO - PROCESS | 1676 | [#4] audio-oscillator Cycles:10 Average:106.00 Median:79.50 stddev:26.14 (32.9%) stddev-sans-first:26.11 08:30:50 INFO - PROCESS | 1676 | Values: 81.0 80.0 122.0 79.0 82.0 96.0 140.0 115.0 116.0 149.0 08:30:50 INFO - PROCESS | 1676 | 08:30:50 INFO - PROCESS | 1676 | [#5] imaging-gaussian-blur Cycles:10 Average:89.10 Median:90.00 stddev:2.18 (2.4%) stddev-sans-first:2.05 08:30:50 INFO - PROCESS | 1676 | Values: 92.0 88.0 90.0 87.0 90.0 87.0 92.0 88.0 91.0 86.0 08:30:50 INFO - PROCESS | 1676 | 08:30:50 INFO - PROCESS | 1676 | [#6] imaging-darkroom Cycles:10 Average:97.30 Median:95.00 stddev:4.92 (5.2%) stddev-sans-first:4.97 08:30:50 INFO - PROCESS | 1676 | Values: 93.0 96.0 95.0 95.0 95.0 107.0 95.0 96.0 106.0 95.0 08:30:50 INFO - PROCESS | 1676 | 08:30:50 INFO - PROCESS | 1676 | [#7] imaging-desaturate Cycles:10 Average:91.80 Median:91.50 stddev:3.29 (3.6%) stddev-sans-first:2.24 08:30:50 INFO - PROCESS | 1676 | Values: 99.0 95.0 90.0 92.0 91.0 90.0 89.0 94.0 89.0 89.0 08:30:50 INFO - PROCESS | 1676 | 08:30:50 INFO - PROCESS | 1676 | [#8] json-parse-financial Cycles:10 Average:65.60 Median:66.50 stddev:5.23 (7.9%) stddev-sans-first:5.14 08:30:50 INFO - PROCESS | 1676 | Values: 60.0 63.0 66.0 65.0 68.0 67.0 67.0 78.0 62.0 60.0 08:30:50 INFO - PROCESS | 1676 | 08:30:50 INFO - PROCESS | 1676 | [#9] json-stringify-tinderbox Cycles:10 Average:72.90 Median:72.50 stddev:7.52 (10.4%) stddev-sans-first:7.07 08:30:50 INFO - PROCESS | 1676 | Values: 63.0 68.0 67.0 73.0 72.0 75.0 70.0 70.0 83.0 88.0 08:30:50 INFO - PROCESS | 1676 | 08:30:50 INFO - PROCESS | 1676 | [#10] stanford-crypto-aes Cycles:10 Average:83.90 Median:84.00 stddev:2.81 (3.3%) stddev-sans-first:2.77 08:30:50 INFO - PROCESS | 1676 | Values: 81.0 82.0 84.0 83.0 86.0 81.0 84.0 82.0 86.0 90.0 08:30:50 INFO - PROCESS | 1676 | 08:30:50 INFO - PROCESS | 1676 | [#11] stanford-crypto-ccm Cycles:10 Average:140.80 Median:140.00 stddev:19.83 (14.2%) stddev-sans-first:20.71 08:30:50 INFO - PROCESS | 1676 | Values: 131.0 135.0 145.0 151.0 132.0 125.0 125.0 117.0 172.0 175.0 08:30:50 INFO - PROCESS | 1676 | 08:30:50 INFO - PROCESS | 1676 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.30 Median:169.50 stddev:23.04 (13.6%) stddev-sans-first:24.28 08:30:50 INFO - PROCESS | 1676 | Values: 169.0 169.0 170.0 170.0 169.0 156.0 154.0 216.0 221.0 169.0 08:30:50 INFO - PROCESS | 1676 | 08:30:50 INFO - PROCESS | 1676 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.50 Median:72.50 stddev:15.41 (21.3%) stddev-sans-first:15.87 08:30:50 INFO - PROCESS | 1676 | Values: 66.0 67.0 78.0 95.0 103.0 77.0 60.0 92.0 59.0 68.0 08:30:50 INFO - PROCESS | 1676 | -------- Summary: end -------- 08:30:50 INFO - PROCESS | 1676 | 08:30:50 INFO - TEST-INFO | 1676: exit 0 08:30:50 INFO - TEST-OK | kraken | took 59011ms 08:30:50 INFO - SUITE-END | took 1170s 08:30:51 INFO - Completed test suite (00:19:30) 08:30:51 INFO - javascript benchmark 08:30:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3484.0, 3532.08165876872, 3549.041758725146, 3562.9821850890744, 3589.300086483135, 5779.162021506879, 5779.76880924763, 5795.536357091433, 5799.420057994198, 5817.098349755784, 5801.709914504279, 5802.158686990384, 5808.390119037493, 5808.767649294024, 5845.357010728827, 5208.010478009185, 5237.7904883804795, 5264.341957255343, 5299.28459657947, 5306.177542480922, 5199.662021968579, 5248.29148065012, 5302.231176479428, 5330.973345133289, 5458.6724796512135, 5279.524842764151, 5284.5772338212855, 5292.179712144604, 5306.124489459235, 5374.4894235047805, 4583.175028494869, 4934.80260789568, 5355.812546560852, 5362.0, 5384.0, 5216.139337009389, 5293.100172970604, 5316.12283973144, 5323.946760532383, 5335.386430560497, 5570.136628822539, 5688.11834165705, 5701.486866182022, 5743.942560574383, 5796.42035796417, 5348.973255133738, 6180.412860778219, 6246.593971391845, 6254.124422580842, 6308.022256550242, 6273.309935907047, 6274.905876411857, 6287.9056814147825, 6292.1820163378925, 6299.181106456177, 5752.395998420147, 5899.734511946973, 5942.37605051472, 5945.970270148665, 5965.463108320272, 6259.561830671901, 6304.085907543381, 6326.873462530723, 6334.873302533923, 6343.365663433619, 6246.906296405557, 6250.218722659668, 6263.498920086382, 6271.090691849657, 6280.309165991737, 6248.062790581358, 6280.340564240689, 6283.403076707706, 6328.968355158195, 6329.936700633027, 5371.543418809445, 5713.914291285634, 5792.623479473821, 6042.395760423922, 6285.214348206474, 6145.0950205435565, 6318.968405157945, 6323.683815809191, 6345.524085693499, 6352.078948552434, 4670.859874203779, 4759.452662943806, 4998.175301075355, 5028.270900719375, 5041.495850415003, 3822.808859557011, 4544.863654090383, 4567.680262381601, 4583.954160458353, 4592.6555508337005, 5831.708414579254, 5933.554983376178, 5952.285725712942, 6005.189299444606, 6033.5173186145, 6184.474319682788, 6246.562740608205, 6254.781082662085, 6312.621242725451, 6355.586886852339, 6011.519078473799, 6014.849628759316, 6041.788537401258, 6044.214252147192, 6051.920685884305, 5430.42980487051, 5506.669599824023, 5569.610127291051, 5590.832275031756, 5601.467860553241, 6461.0631458438265, 6515.153030106056, 6527.314631963575, 6541.6075035498025, 6549.803505894831, 6308.52686048539, 6329.8734025320155, 6335.208098987627, 6338.334474880071, 6428.260750013808, 6365.554411191358, 6426.550141490238, 6509.0, 6518.967405162944, 6546.967265163644, 6247.437730604267, 6263.96868015657, 6267.561270711007, 6269.435750782452, 6276.932921403281, 6285.779997700059, 6305.274893387319, 6311.337309582611, 6328.367163283635, 6349.301576826638, 5987.730552125252, 6055.364186760326, 6112.0171844544475, 6212.968935155295, 6238.875222495615, 5195.636305458582, 6135.53983451234, 6181.721822517908, 6217.658028808278, 6224.190855188796, 6049.697515124139, 6055.394460553909, 6082.695865206811, 6127.816165515042, 6144.938550614436, 5467.917981230205, 5940.990031694709, 6194.101855230876, 6219.440250377488, 6236.844078897882, 6106.297775755845, 6107.348821471691, 6119.2350956130485, 6121.234845644294, 6126.988877250665], "value": 5771.698876039662, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [491.44466752569593, 520.1495554767954, 532.4808311895899, 586.9236999190106, 635.5424094651852, 29570.40859182815, 38977.0, 38986.41520377197, 38990.0, 39013.804930975304, 39460.21079578406, 39474.8026259869, 39717.80141099298, 40225.59774402263, 40363.79818100906, 9445.0, 9565.66520171794, 9624.03759624039, 9632.61469541221, 9640.180584650314, 9530.0, 9774.31579789415, 9779.902200977971, 9793.657221997231, 9854.901450985506, 9785.706428807147, 9830.508474576278, 9843.704688859345, 9857.211423086172, 9876.160526355268, 9770.413775173476, 9781.266405019653, 9802.558884850163, 9848.65529706464, 9867.506624668773, 9673.661421850273, 9721.854172187423, 9722.076402741728, 9754.512274386323, 9778.511074446249, 38378.42432363517, 38455.23089538194, 38580.03549911247, 38662.22675546473, 38737.80631096826, 3249.7237734792334, 3325.88359407422, 3384.8984530464127, 3431.811250381228, 3485.8082805445692, 27495.0, 28321.57517637237, 28490.430191396055, 28541.28646783826, 28801.855990719912, 2736.097087960968, 2798.930026749327, 2830.872610732522, 2851.6863145054026, 2880.9567856482167, 359186.0, 360533.0, 361784.0, 361908.9999999974, 362894.1855290733, 66084.66957665181, 66128.66935665291, 66684.66630667457, 66695.66652166708, 66727.0, 69699.3030069703, 71509.2849071513, 71511.85464436075, 71744.0, 71756.0, 17878.91060544715, 17981.730274045898, 18085.36701215451, 18110.0, 18151.0, 11485.712857178638, 13392.732145357038, 13573.52492662752, 13616.114952528054, 13666.0, 19984.90007549953, 20151.59696806085, 20160.69758953617, 20199.4950126248, 20256.392308230777, 7993.960030199928, 8137.75586732399, 8148.0, 8172.713955011546, 8205.917940820635, 989.9950500247453, 1082.3397727386289, 1166.1079274355125, 1180.698921774942, 1209.7943349630584, 30485.23786905345, 30544.0, 30699.846500767355, 30858.537121943187, 31302.90439834595, 1096.7751610919902, 1102.4818335382358, 1128.8193888977723, 1160.3618010094415, 1166.049669519356, 13669.838063764493, 13717.0398072134, 15166.31751571182, 16442.506724798277, 16599.336026559045, 508147.0, 508783.0, 511041.0, 514269.0, 517574.0, 58056.0, 58835.0, 59134.11298830521, 59445.40554594485, 59510.0, 1394.6583087143715, 1483.784851196592, 1508.8415716349846, 1514.606202387365, 1525.046845721424, 29941.550876737303, 31281.374372511968, 31301.217469563442, 31600.841995789873, 31604.0, 54009.72995134999, 55088.0, 55295.72352138213, 55898.0, 56236.71881640566, 585702.0, 588873.0, 589051.0, 590788.0, 596005.0, 27267.318317042234, 27300.45399092047, 27764.861175693994, 27978.860105699343, 28168.01411950572, 45008.0, 45605.77197113993, 47006.294905577124, 47383.76308118437, 47683.52316476929, 38866.437110382125, 38965.0, 38985.805070974464, 39203.607963919996, 39335.213295734495, 38328.42507362336, 38476.03809904775, 38674.226515470094, 38973.8051309753, 39012.60987390203, 32765.0, 38290.0, 38457.4231386535, 38706.8387948362, 38716.6128338713, 30326.393472129992, 31858.522122167724, 32083.197920052186, 32286.35427291488, 32333.838330808194, 24080.63879041781, 24298.14956476515, 24686.01255949742, 24857.25428237156, 25178.496430071664], "value": 20061.549530366756, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [401.3117503481529, 402.255826720567, 403.33047141744674, 408.4159651698071, 415.323023471741, 14987.775183372258, 19680.015999200055, 20327.1869125235, 20354.89822550887, 20432.795672043274, 19787.0, 19797.604047919034, 19834.900825495853, 20040.0, 20119.899400503014, 5575.498205161534, 5646.0, 5850.76596936122, 5866.501347385477, 5874.471297583216, 5709.628874123189, 5713.085906255, 5717.514011309023, 5744.080947048473, 5831.2419385479825, 5706.77172913083, 6134.631922084667, 6577.835554111161, 6598.142241508597, 6600.966995165041, 5765.7981970631035, 5828.6502809831545, 6461.6446095464735, 6552.57408268461, 6588.407043366096, 4993.325901003354, 5106.795728170869, 5583.637063590894, 5592.356878958931, 5595.18869763882, 12870.613881583567, 12897.032722545753, 23185.53628927429, 24261.0, 24292.878535607386, 2070.7515098188164, 2137.2733270687886, 2202.064122747826, 2318.26974503031, 2648.4041090754617, 8005.6797728090805, 8555.33171031649, 9622.951885240598, 9655.662051828223, 9999.25005624581, 1626.7315892877662, 1690.2647348403484, 1726.7064599018197, 1753.6755700195376, 1798.1728404933615, 111795.0, 117657.41171294088, 117683.41158294154, 119914.0, 120493.39753301177, 11919.582814601446, 12019.038476921825, 12034.977026952807, 12066.155369124253, 12101.882170620107, 14860.0, 14945.476908308157, 15016.849831501764, 15178.848211517743, 15193.696126077417, 8975.820483590292, 8998.0, 9005.459672419676, 9040.502772347452, 9105.180533751995, 8981.191692747683, 9052.094790520896, 9115.772105697411, 9130.81738365229, 9233.261339092856, 4611.515790841981, 4626.329182268552, 4679.485256621769, 4680.602148817388, 4691.7654117294, 1916.4059141666128, 1917.0031583576463, 1986.5530255692354, 2049.5695903860355, 2061.29915828619, 859.6819176904586, 919.8666193402053, 972.4602845420859, 1077.3535878472853, 1101.2016288191157, 20263.898680506798, 20373.49066273355, 20399.796002040086, 20866.26968056111, 20901.954902254827, 1006.2503434941016, 1006.5219020964983, 1017.5471914997755, 1022.2282176956351, 1031.1132426113536, 9046.276297896153, 10794.946025269823, 10815.35107893529, 10975.670729878117, 11057.170712196457, 588835.0, 603385.0, 610679.0, 611169.0, 611650.0, 124017.37991309985, 128373.223204956, 130068.34965825299, 131969.0, 133103.66896331107, 1011.4740335025746, 1028.7993841200894, 1030.829913064336, 1041.3231399590327, 1041.7864337810731, 17787.199576019368, 17846.929184248987, 18162.909185454515, 18241.726374104663, 18334.816651833844, 47786.28320575125, 48667.513324867716, 49681.50318496769, 50946.0, 51889.22166167586, 74141.0, 75449.6227518859, 76690.0, 77541.44917101739, 77819.0, 7043.788686339418, 8035.598220089089, 8051.074126475531, 8060.355171586376, 8358.707445239388, 10740.46297685097, 12371.75256494883, 12456.875431245571, 12642.494300227889, 12681.30252836103, 10635.255532112678, 10670.19973502006, 10684.732881678021, 10916.727081823017, 10952.288101273392, 8193.959030204811, 9467.100625440573, 10539.15686745074, 10541.4729263535, 10785.0, 10716.249862509549, 10995.890041099807, 11144.49849756779, 11183.217174797686, 11226.101911847196, 7850.803729906798, 7995.560244186626, 8201.30288925448, 8233.34133269349, 8483.024452188078, 8267.127001736188, 11072.612458563912, 11083.778324433628, 11164.330140191614, 11359.204855660355], "value": 9479.76043312564, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1567.6002619332069, 1599.392230952238, 1601.6956778212138, 1944.3194881791376, 2008.6986951957215, 8002.439829211958, 8641.222289993899, 8841.513716745576, 9058.547072646366, 9077.319201059921, 8742.344324175681, 8848.29213662907, 8887.333449991243, 8891.777705557364, 8928.687495937644, 2467.7532246775313, 2483.428811373385, 2496.588062969608, 2507.9749202508015, 2513.9371515712164, 2500.0499810072147, 2511.4098186926058, 2515.7987361011164, 2535.2014115553634, 2541.745825417462, 2456.668349772776, 2459.827812053157, 2496.1263557754824, 2507.5110353481077, 2522.8486290822516, 2468.6790717206736, 2477.6283557466436, 2479.0207867892173, 2499.6125600531946, 2500.437401584647, 2505.924822255335, 2519.0427637497633, 2548.120898290087, 2550.6301586270074, 2552.9106481273066, 8960.372773905829, 8973.820523589557, 9012.594433250453, 9040.77398065047, 9047.728568142966, 5395.676259424408, 5522.171674248895, 5582.888342233133, 5596.552275817925, 5599.580031497654, 3587.910302242439, 3669.4679271505483, 3674.7978861162624, 3694.722895782827, 3719.497867787868, 1040.625374865048, 1057.8730552333768, 1059.4967390489533, 1061.0185578340004, 1061.1775873697868, 11820.29078255299, 11857.407129643483, 12048.156629035971, 12049.518019279307, 12087.818682719766, 4138.482689663792, 4879.4388645305535, 4947.35684361038, 4981.227909673934, 5051.4695956924725, 10279.743006424898, 10349.0, 10475.528601212964, 10494.0, 10533.894661053444, 5548.472895075042, 5589.301337332833, 5590.748416321276, 5598.440155984369, 5608.803691870765, 4498.932516012263, 4905.435874874363, 4966.90066198674, 5005.499450054966, 5096.490350964948, 3868.5937976512623, 3916.4516967624554, 4124.711270211056, 4198.454200953856, 4260.573942605714, 1357.9321033948263, 1387.3895485986134, 1389.5831250624772, 1389.5970168651181, 1398.7482253194523, 927.5176908007799, 933.8047299456706, 943.4527973775193, 951.2532661860406, 956.1776871890169, 5835.4748072673665, 5920.0, 5932.0, 6082.939170608326, 6176.845578860474, 1025.0774303127096, 1033.813913495563, 1083.8699356077163, 1097.9451027448595, 1127.537709539085, 3943.0, 4002.0995276062654, 4039.555648878621, 4042.191561687674, 4043.5147782265726, 10635.574577016832, 11059.22585419029, 11090.889091109148, 11102.500387482422, 11128.332300062022, 10706.1435085193, 10865.945670271598, 10870.782584348268, 10924.563017479211, 10957.780844383067, 7049.577025378494, 7217.2421895701245, 7945.801354966172, 8423.74728758126, 8489.278411334983, 5399.676019438689, 5433.038993748496, 5572.944270557243, 5695.658260504383, 6020.759169633341, 7764.378849692124, 7994.720184793504, 9553.856692149484, 9671.468069256258, 9751.658691945748, 9307.395019323721, 9706.272029597667, 9732.854007189757, 9874.160696340892, 9945.30382873192, 6878.8624227514165, 7304.707811687473, 7403.852402877563, 7524.17234104259, 7543.811404714706, 8251.587420629065, 8319.916800831914, 8349.582520874053, 8374.0, 8385.28725058377, 7346.4122870169185, 7509.586972716553, 7558.697652093854, 7614.428917831074, 7629.961850190935, 7314.305141011595, 7399.149097853816, 7405.0, 7489.13874904393, 7496.700131994878, 6879.96800479932, 6897.62063086515, 6922.619255940972, 6928.549644273106, 6949.583025018313, 4791.353167322506, 4893.412790465167, 4959.628027897994, 4962.404511458648, 4979.626528010342, 4918.43438004634, 4925.704457732548, 4931.605471562195, 4949.985253023194, 5220.034293655534], "value": 4697.156986436065, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [282.7441165745001, 323.1017770597738, 331.20015163380435, 337.1302040734903, 337.93072420153874, 5460.808871689486, 6725.260221375657, 6812.727490900371, 6899.758508452205, 6932.4454043676515, 5158.613104017204, 6899.310068993098, 6901.206361268443, 6943.618101004443, 6951.652417379123, 5033.496650334965, 5075.796968121271, 5080.568151707091, 5084.211947148198, 5106.757209172165, 5083.415407228161, 5093.26147708582, 5094.439611642716, 5099.643024988234, 5100.387953445592, 5001.424836143854, 5008.699478031312, 5029.1953287473825, 5036.34527511423, 5046.293518907356, 4023.436718859362, 4057.3913912913004, 4327.701689493171, 4871.5372039655995, 5028.597712183035, 3958.920821583581, 4148.43996060531, 4150.522689890671, 4154.480689913761, 4168.7081904266715, 4653.766751810773, 5837.620554663976, 6592.241892182413, 6598.93401065988, 6647.1026411434805, 4811.085893680224, 5846.298444186725, 5850.9414905851245, 5912.379200184005, 5923.614965027303, 5608.653923058478, 7359.153697324823, 7359.3744531714865, 7368.3736882365065, 7403.074615673041, 3951.3480275754464, 4484.865454036384, 4536.160810250114, 4710.481846996828, 4714.905701885943, 7622.771316860503, 7704.8844267336035, 7719.729809456642, 7743.225677432324, 7803.765887023398, 4774.522547745198, 4779.211430114062, 4783.641226907996, 4784.067106914188, 4807.591354734816, 4857.441394239637, 4858.684185527929, 4871.805127794849, 4880.585150262196, 4881.487443818348, 5852.356240813507, 5859.267591551056, 5861.824145275648, 5878.883012227663, 5883.0881213411985, 7140.750073747394, 7401.444891633149, 7405.037345145097, 7428.62856857155, 7439.107307123179, 1668.6329007618303, 1670.3903075377643, 1691.7462380643003, 1696.3129932377408, 1698.9405370811962, 995.0894931138073, 1050.1965996013057, 1055.6516349604613, 1069.6470164845582, 1073.5491093740648, 6798.824376199667, 6853.040574319599, 6874.759383421556, 6914.965425172842, 6929.5842249465195, 7690.692372305157, 7696.499727517694, 7713.8457230855065, 7720.266574675396, 7722.613869306625, 6975.895361569581, 6978.476614253951, 6980.860382792316, 7004.299570042955, 7026.80544307469, 3907.296686596383, 4075.2664520386024, 4085.652719518874, 4091.754494730326, 4093.0790572121036, 7332.680117578887, 7539.982102416213, 8140.3487720982175, 8189.9762529683785, 8265.793355166168, 6019.0, 6427.0, 7959.44283900133, 7998.960005199937, 8007.199280071946, 6911.5831254593695, 8224.8355032899, 8225.629846656915, 8237.423380363436, 8237.711680091168, 7399.18608953004, 7464.440166987606, 7657.731979380695, 7707.807304817425, 7824.374050076094, 7563.903234030925, 7599.354054905174, 7604.8479030417975, 7612.581308028111, 7668.817440762021, 8147.551884646165, 8153.143919888326, 8169.959150204211, 8172.550509721785, 8174.754757357289, 4295.978520107379, 4763.0235801661265, 4776.164171269945, 4782.641301902441, 4786.760661966958, 6092.969535152473, 7410.221926697619, 7625.466217364731, 7626.199249078989, 7650.4644674872225, 7330.670119844726, 7389.926100738924, 7403.370713489414, 7405.925940740523, 7409.925900740924, 7362.889556656547, 7366.7053317866685, 7378.483506154517, 7379.963100184465, 7401.925980740124, 6475.7733479328, 6556.213254409501, 7066.858662826818, 7197.568145911262, 7208.243134471015, 7267.454940879561, 7377.704891804267, 7387.113546374255, 7388.371988381048, 7423.0, 6924.549904256207, 7019.543729657556, 7175.425965922609, 7178.964105179441, 7311.4150867929375], "value": 5286.529675902797, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [695.6069820551389, 737.4174402222245, 749.1422321441946, 751.0949306086167, 764.7782143178479, 15231.999999999985, 15242.46651367201, 15322.846771532282, 15382.615434614141, 15592.0, 13999.30003499826, 14044.29778511073, 14061.296935153252, 14073.64815879601, 14085.084469509475, 724.1238101896711, 903.7424334064793, 912.0423555266959, 927.6845872403381, 935.3078721745893, 919.5769945824928, 924.8982611912684, 926.592299388267, 927.2303987690201, 935.6678379175397, 964.84080126779, 977.8288799460101, 982.592224226945, 985.1380042462845, 985.3053597213947, 934.7709811096257, 953.4041224234853, 966.6085235479607, 971.7910649210419, 973.2019743810079, 712.7256006437507, 713.4043074033189, 718.9496735228536, 728.3845150847526, 729.7081167532976, 14828.184449855253, 14896.925515372353, 14970.251487425694, 15254.08475491463, 15351.155686437241, 12976.805347919788, 13006.154599951067, 13082.95336373088, 13164.60506184816, 13167.934160329138, 9809.607615695435, 10202.693919182362, 10270.89729102707, 10354.430506322149, 10363.533640986097, 3511.262634846685, 3534.1164708822794, 3647.4893514907935, 3678.1356381250384, 3791.6018818023986, 82694.58652706759, 83124.16875831225, 83251.0, 83552.16447835504, 84306.89232769169, 9314.0, 9391.177833472058, 9512.524373781283, 9593.760155996157, 9692.903070969342, 20553.38339849807, 21121.471963201042, 21305.934703264775, 21341.46646333854, 21409.0, 7287.417006639456, 7343.596102214323, 7352.19125896151, 7405.444591655647, 7431.2197219292275, 3384.915377115592, 3409.0, 3987.441758153861, 4097.016715988201, 4101.138760860253, 3079.876804927823, 3605.7656252343513, 3622.673959343672, 3675.5221821163077, 3696.242270334575, 647.1296106736526, 654.3587284461255, 655.3708439897656, 664.2394458345265, 672.2370109925172, 470.5647276269437, 607.6354187487473, 613.2457077794338, 616.14233087242, 618.7524990004034, 7716.344110750537, 8265.752027439186, 8291.0, 8403.117672644285, 8659.350548708871, 595.7200115945499, 598.7156100852061, 610.1488423649073, 616.1466369078769, 619.9225096862892, 3750.456183853326, 3773.6981041516615, 3781.205946751158, 3934.2328245991757, 4019.738716983445, 83380.0, 88667.66998495028, 89020.6646900297, 89507.55246223728, 89726.55136724406, 76810.0, 77101.0, 77159.61420192863, 78312.0, 78629.60685196651, 64327.78361081927, 66736.66631666811, 69743.65128174327, 70540.0, 74182.0, 8225.0, 8500.404971652173, 8569.442986205877, 8625.05124436324, 8691.919519633557, 35138.64861351456, 41357.37963930483, 48702.26946595733, 48790.5120948786, 50606.74696626641, 52280.69298267421, 53457.465425345246, 63330.68334658297, 64650.676746615965, 65213.67393163004, 10805.351678899291, 10832.0, 10856.945715271373, 11156.107511398906, 11499.942500287445, 11622.360770157722, 11633.185677002528, 11688.415579220835, 11793.05655547537, 11814.442186648095, 8925.73222803317, 11031.393273370042, 11095.778084438105, 11350.148738844784, 11462.369569673749, 10932.836007459735, 11192.88807111951, 11217.383043932661, 11300.090769757486, 11616.535338586362, 9262.16640502358, 11037.724056898642, 11043.0, 11044.668659940215, 11593.362365070003, 7916.327112195528, 8179.591020449073, 9587.233021358135, 9638.373505722106, 9810.0, 9502.904970950478, 9582.0, 9650.420974741539, 9703.563339649592, 9766.12104910533], "value": 6859.4124660724165, "unit": "score"}], "name": "dromaeo_css", "value": 7561.925018528859, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 119.0, 108.0, 116.0, 118.0, 118.0, 106.0, 116.0, 117.0, 116.0], "value": 114.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 130.0, 157.0, 155.0, 121.0, 144.0, 146.0, 186.0, 195.0, 123.0], "value": 150.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 198.0, 233.0, 258.0, 196.0, 194.0, 197.0, 200.0, 233.0, 278.0], "value": 218.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 74.0, 71.0, 142.0, 57.0, 56.0, 57.0, 61.0, 126.0, 57.0], "value": 76.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 80.0, 122.0, 79.0, 82.0, 96.0, 140.0, 115.0, 116.0, 149.0], "value": 106.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 88.0, 90.0, 87.0, 90.0, 87.0, 92.0, 88.0, 91.0, 86.0], "value": 89.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 96.0, 95.0, 95.0, 95.0, 107.0, 95.0, 96.0, 106.0, 95.0], "value": 97.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 95.0, 90.0, 92.0, 91.0, 90.0, 89.0, 94.0, 89.0, 89.0], "value": 91.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 63.0, 66.0, 65.0, 68.0, 67.0, 67.0, 78.0, 62.0, 60.0], "value": 65.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 68.0, 67.0, 73.0, 72.0, 75.0, 70.0, 70.0, 83.0, 88.0], "value": 72.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 84.0, 83.0, 86.0, 81.0, 84.0, 82.0, 86.0, 90.0], "value": 83.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 135.0, 145.0, 151.0, 132.0, 125.0, 125.0, 117.0, 172.0, 175.0], "value": 140.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 169.0, 170.0, 170.0, 169.0, 156.0, 154.0, 216.0, 221.0, 169.0], "value": 176.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 78.0, 95.0, 103.0, 77.0, 60.0, 92.0, 59.0, 68.0], "value": 76.5, "unit": "score"}], "name": "kraken", "value": 1560.8, "alertThreshold": 2.0}]} 08:30:51 INFO - Return code: 0 08:30:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 08:30:51 ERROR - # TBPL SUCCESS # 08:30:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:30:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:30:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:30:51 INFO - 2016-05-30 08:30:51.543 screenresolution[1682:10291] starting screenresolution argv=screenresolution get 08:30:51 INFO - 2016-05-30 08:30:51.546 screenresolution[1682:10291] Display 0: 1600x1200x32@60 08:30:51 INFO - 2016-05-30 08:30:51.551 screenresolution[1683:10294] starting screenresolution argv=screenresolution list 08:30:51 INFO - Available Modes on Display 0 08:30:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:30:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:30:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:30:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:30:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:30:51 INFO - Intel Iris: 08:30:51 INFO - Chipset Model: Intel Iris 08:30:51 INFO - Type: GPU 08:30:51 INFO - Bus: Built-In 08:30:51 INFO - VRAM (Dynamic, Max): 1536 MB 08:30:51 INFO - Vendor: Intel (0x8086) 08:30:51 INFO - Device ID: 0x0a2e 08:30:51 INFO - Revision ID: 0x0009 08:30:51 INFO - Displays: 08:30:51 INFO - Display: 08:30:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:30:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:30:51 INFO - Display Serial Number: 1600x1200 60 08:30:51 INFO - Main Display: Yes 08:30:51 INFO - Mirror: Off 08:30:51 INFO - Online: Yes 08:30:51 INFO - Rotation: Supported 08:30:51 INFO - Return code: 0 08:30:51 INFO - Running post-action listener: _resource_record_post_action 08:30:51 INFO - Running post-run listener: _resource_record_post_run 08:30:52 INFO - Total resource usage - Wall time: 1204s; CPU: 53.0%; Read bytes: 37545472; Write bytes: 655744512; Read time: 393; Write time: 1932 08:30:52 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 351083008; Write bytes: 346855424; Read time: 27432; Write time: 691 08:30:52 INFO - run-tests - Wall time: 1173s; CPU: 53.0%; Read bytes: 35730944; Write bytes: 298096128; Read time: 373; Write time: 1206 08:30:52 INFO - Running post-run listener: _upload_blobber_files 08:30:52 INFO - Blob upload gear active. 08:30:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:30:52 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 08:30:52 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'] 08:30:52 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 08:30:53 INFO - (blobuploader) - INFO - Open directory for files ... 08:30:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:30:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:30:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:30:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:30:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:30:53 INFO - (blobuploader) - INFO - Done attempting. 08:30:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:30:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:30:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:30:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:30:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:30:54 INFO - (blobuploader) - INFO - Done attempting. 08:30:54 INFO - (blobuploader) - INFO - Iteration through files over. 08:30:54 INFO - Return code: 0 08:30:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:30:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:30:54 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cd41127104e9055d7bfc2d09b4a6f2ddeac18ebf88b622e1863a6f080d6f09015d2f0fedb3931b9ed7f42ef5c86f16518558566b16fea9fad2b99147393d4d7c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cb5368aaaebd1ac40571c277890f8aa4508b7030c0661e2ef441c8766cf424a9c70c6db08d38676d5fa0aba811adf18deb52b51b88136e12166d25dde9457755"} 08:30:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:30:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:30:54 INFO - Contents: 08:30:54 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cd41127104e9055d7bfc2d09b4a6f2ddeac18ebf88b622e1863a6f080d6f09015d2f0fedb3931b9ed7f42ef5c86f16518558566b16fea9fad2b99147393d4d7c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cb5368aaaebd1ac40571c277890f8aa4508b7030c0661e2ef441c8766cf424a9c70c6db08d38676d5fa0aba811adf18deb52b51b88136e12166d25dde9457755"} 08:30:54 INFO - Running post-run listener: copy_logs_to_upload_dir 08:30:54 INFO - Copying logs to upload dir... 08:30:54 INFO - mkdir: /builds/slave/test/build/upload/logs 08:30:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.774696 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-05-30 08:30:54.985143) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 08:30:54.988402) ========= 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.XIJvsp4BeQ/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.QymtpaQV4F/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cd41127104e9055d7bfc2d09b4a6f2ddeac18ebf88b622e1863a6f080d6f09015d2f0fedb3931b9ed7f42ef5c86f16518558566b16fea9fad2b99147393d4d7c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cb5368aaaebd1ac40571c277890f8aa4508b7030c0661e2ef441c8766cf424a9c70c6db08d38676d5fa0aba811adf18deb52b51b88136e12166d25dde9457755"} build_url:https://queue.taskcluster.net/v1/task/eAhkcfpqSpmaq-FV_-pdbw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010735 build_url: 'https://queue.taskcluster.net/v1/task/eAhkcfpqSpmaq-FV_-pdbw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cd41127104e9055d7bfc2d09b4a6f2ddeac18ebf88b622e1863a6f080d6f09015d2f0fedb3931b9ed7f42ef5c86f16518558566b16fea9fad2b99147393d4d7c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cb5368aaaebd1ac40571c277890f8aa4508b7030c0661e2ef441c8766cf424a9c70c6db08d38676d5fa0aba811adf18deb52b51b88136e12166d25dde9457755"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 08:30:55.018497) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 08:30:55.018829) ========= 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.XIJvsp4BeQ/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.QymtpaQV4F/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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.005016 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 08:30:55.067865) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 08:30:55.068205) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 08:30:55.068578) ========= ========= Total master_lag: 0.16 =========