builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0166 starttime: 1461280822.86 results: success (0) buildid: 20160421142616 builduid: 707ec584c3e74713ad199462b34f5800 revision: 6e5771e2760a4093e716784f3d69a737a25b1dbf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-21 16:20:22.861479) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-21 16:20:22.861887) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-21 16:20:22.862167) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-21 16:20:22.895855) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 16:20:22.896123) ========= 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.OEZMWScpxd/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.yycfIiOqIF/Listeners TMPDIR=/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-21 16:20:22-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 13.1M=0.001s 2016-04-21 16:20:23 (13.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.136910 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 16:20:23.050407) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 16:20:23.051339) ========= 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.OEZMWScpxd/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.yycfIiOqIF/Listeners TMPDIR=/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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.026697 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 16:20:23.100660) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-21 16:20:23.101460) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 6e5771e2760a4093e716784f3d69a737a25b1dbf --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 6e5771e2760a4093e716784f3d69a737a25b1dbf --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.OEZMWScpxd/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.yycfIiOqIF/Listeners TMPDIR=/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-21 16:20:23,179 truncating revision to first 12 chars 2016-04-21 16:20:23,180 Setting DEBUG logging. 2016-04-21 16:20:23,180 attempt 1/10 2016-04-21 16:20:23,180 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/6e5771e2760a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-21 16:20:24,099 unpacking tar archive at: mozilla-central-6e5771e2760a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.170633 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-21 16:20:24.289613) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-21 16:20:24.289960) ========= script_repo_revision: 6e5771e2760a4093e716784f3d69a737a25b1dbf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-21 16:20:24.290393) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-21 16:20:24.290686) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-21 16:20:24.304066) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-04-21 16:20:24.304456) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.OEZMWScpxd/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.yycfIiOqIF/Listeners TMPDIR=/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 16:20:24 INFO - MultiFileLogger online at 20160421 16:20:24 in /builds/slave/test 16:20:24 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 16:20:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:20:24 INFO - {'append_to_log': False, 16:20:24 INFO - 'base_work_dir': '/builds/slave/test', 16:20:24 INFO - 'blob_upload_branch': 'Firefox', 16:20:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:20:24 INFO - 'branch': 'Firefox', 16:20:24 INFO - 'buildbot_json_path': 'buildprops.json', 16:20:24 INFO - 'config_files': ('talos/mac_config.py',), 16:20:24 INFO - 'default_actions': ('clobber', 16:20:24 INFO - 'read-buildbot-config', 16:20:24 INFO - 'download-and-extract', 16:20:24 INFO - 'populate-webroot', 16:20:24 INFO - 'create-virtualenv', 16:20:24 INFO - 'install', 16:20:24 INFO - 'run-tests'), 16:20:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:20:24 INFO - 'download_minidump_stackwalk': True, 16:20:24 INFO - 'download_symbols': 'ondemand', 16:20:24 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:20:24 INFO - 'tooltool.py': '/tools/tooltool.py', 16:20:24 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:20:24 INFO - '/tools/misc-python/virtualenv.py')}, 16:20:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:20:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:20:24 INFO - 'installer_path': 'installer.exe', 16:20:24 INFO - 'log_level': 'info', 16:20:24 INFO - 'log_name': 'talos', 16:20:24 INFO - 'log_to_console': True, 16:20:24 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:20:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:20:24 INFO - 'opt_config_files': (), 16:20:24 INFO - 'pip_index': False, 16:20:24 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:20:24 INFO - 'cmd': ('bash', 16:20:24 INFO - '-c', 16:20:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:20:24 INFO - 'enabled': True, 16:20:24 INFO - 'halt_on_failure': False, 16:20:24 INFO - 'name': 'check_screen_resolution'},), 16:20:24 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:20:24 INFO - 'cmd': ('bash', 16:20:24 INFO - '-c', 16:20:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:20:24 INFO - 'enabled': True, 16:20:24 INFO - 'halt_on_failure': False, 16:20:24 INFO - 'name': 'check_screen_resolution'},), 16:20:24 INFO - 'run_cmd_checks_enabled': True, 16:20:24 INFO - 'sps_profile': False, 16:20:24 INFO - 'sps_profile_interval': 0, 16:20:24 INFO - 'suite': 'dromaeojs-e10s', 16:20:24 INFO - 'system_bits': '32', 16:20:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:20:24 INFO - 'title': 't-yosemite-r7-0166', 16:20:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:20:24 INFO - 'use_talos_json': True, 16:20:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:20:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:20:24 INFO - 'work_dir': 'build'} 16:20:24 INFO - ##### 16:20:24 INFO - ##### Running clobber step. 16:20:24 INFO - ##### 16:20:24 INFO - Running pre-action listener: _resource_record_pre_action 16:20:24 INFO - Running main action method: clobber 16:20:24 INFO - rmtree: /builds/slave/test/build 16:20:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:20:27 INFO - Running post-action listener: _resource_record_post_action 16:20:27 INFO - ##### 16:20:27 INFO - ##### Running read-buildbot-config step. 16:20:27 INFO - ##### 16:20:27 INFO - Running pre-action listener: _resource_record_pre_action 16:20:27 INFO - Running main action method: read_buildbot_config 16:20:27 INFO - Using buildbot properties: 16:20:27 INFO - { 16:20:27 INFO - "project": "", 16:20:27 INFO - "product": "firefox", 16:20:27 INFO - "script_repo_revision": "production", 16:20:27 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 16:20:27 INFO - "repository": "", 16:20:27 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 16:20:27 INFO - "buildid": "20160421142616", 16:20:27 INFO - "pgo_build": "False", 16:20:27 INFO - "basedir": "/builds/slave/test", 16:20:27 INFO - "buildnumber": 251, 16:20:27 INFO - "slavename": "t-yosemite-r7-0166", 16:20:27 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 16:20:27 INFO - "platform": "yosemite_r7", 16:20:27 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 16:20:27 INFO - "branch": "mozilla-central", 16:20:27 INFO - "repo_path": "mozilla-central", 16:20:27 INFO - "revision": "6e5771e2760a4093e716784f3d69a737a25b1dbf", 16:20:27 INFO - "stage_platform": "macosx64", 16:20:27 INFO - "builduid": "707ec584c3e74713ad199462b34f5800", 16:20:27 INFO - "slavebuilddir": "test" 16:20:27 INFO - } 16:20:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/fKS3xRVXT_2xF-eilOH6FA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 16:20:27 INFO - Running post-action listener: _resource_record_post_action 16:20:27 INFO - ##### 16:20:27 INFO - ##### Running download-and-extract step. 16:20:27 INFO - ##### 16:20:27 INFO - Running pre-action listener: _resource_record_pre_action 16:20:27 INFO - Running main action method: download_and_extract 16:20:27 INFO - mkdir: /builds/slave/test/build/tests 16:20:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:20:27 INFO - https://queue.taskcluster.net/v1/task/fKS3xRVXT_2xF-eilOH6FA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 16:20:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fKS3xRVXT_2xF-eilOH6FA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 16:20:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fKS3xRVXT_2xF-eilOH6FA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 16:20:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fKS3xRVXT_2xF-eilOH6FA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 16:20:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fKS3xRVXT_2xF-eilOH6FA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 16:20:27 INFO - Downloaded 1199 bytes. 16:20:27 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 16:20:27 INFO - Using the following test package requirements: 16:20:27 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 16:20:27 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 16:20:27 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 16:20:27 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 16:20:27 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 16:20:27 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 16:20:27 INFO - u'jsshell-mac.zip'], 16:20:27 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 16:20:27 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 16:20:27 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 16:20:27 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 16:20:27 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 16:20:27 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 16:20:27 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 16:20:27 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 16:20:27 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 16:20:27 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 16:20:27 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 16:20:27 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:20:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:20:27 INFO - https://queue.taskcluster.net/v1/task/fKS3xRVXT_2xF-eilOH6FA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:20:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fKS3xRVXT_2xF-eilOH6FA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 16:20:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fKS3xRVXT_2xF-eilOH6FA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 16:20:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fKS3xRVXT_2xF-eilOH6FA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 16:20:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fKS3xRVXT_2xF-eilOH6FA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:20:28 INFO - Downloaded 18099929 bytes. 16:20:28 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:20:28 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:20:32 INFO - Return code: 0 16:20:32 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 16:20:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:20:32 INFO - https://queue.taskcluster.net/v1/task/fKS3xRVXT_2xF-eilOH6FA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:20:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fKS3xRVXT_2xF-eilOH6FA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 16:20:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fKS3xRVXT_2xF-eilOH6FA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 16:20:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fKS3xRVXT_2xF-eilOH6FA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 16:20:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fKS3xRVXT_2xF-eilOH6FA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:20:33 INFO - Downloaded 18099929 bytes. 16:20:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:20:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:20:36 INFO - Return code: 0 16:20:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:20:36 INFO - https://queue.taskcluster.net/v1/task/fKS3xRVXT_2xF-eilOH6FA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 16:20:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fKS3xRVXT_2xF-eilOH6FA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 16:20:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fKS3xRVXT_2xF-eilOH6FA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 16:20:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fKS3xRVXT_2xF-eilOH6FA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 16:20:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fKS3xRVXT_2xF-eilOH6FA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:20:38 INFO - Downloaded 10845952 bytes. 16:20:38 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:20:38 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 16:20:38 INFO - Return code: 0 16:20: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')]} 16:20:38 INFO - https://queue.taskcluster.net/v1/task/fKS3xRVXT_2xF-eilOH6FA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 16:20:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fKS3xRVXT_2xF-eilOH6FA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 16:20:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fKS3xRVXT_2xF-eilOH6FA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 16:20:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fKS3xRVXT_2xF-eilOH6FA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 16:20:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fKS3xRVXT_2xF-eilOH6FA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 16:20:42 INFO - Downloaded 109971971 bytes. 16:20:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fKS3xRVXT_2xF-eilOH6FA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 16:20:42 INFO - mkdir: /builds/slave/test/properties 16:20:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:20:42 INFO - Writing to file /builds/slave/test/properties/build_url 16:20:42 INFO - Contents: 16:20:42 INFO - build_url:https://queue.taskcluster.net/v1/task/fKS3xRVXT_2xF-eilOH6FA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 16:20:43 INFO - Running post-action listener: _resource_record_post_action 16:20:43 INFO - Running post-action listener: set_extra_try_arguments 16:20:43 INFO - ##### 16:20:43 INFO - ##### Running populate-webroot step. 16:20:43 INFO - ##### 16:20:43 INFO - Running pre-action listener: _resource_record_pre_action 16:20:43 INFO - Running main action method: populate_webroot 16:20:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:20:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:20:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:20:43 INFO - '--tppagecycles', 16:20:43 INFO - '7'], 16:20:43 INFO - 'tests': ['tsvgm']}}, 16:20:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:20:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:20:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:20:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:20:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:20:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:20:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:20:43 INFO - 'talos_options': ['--disable-e10s'], 16:20:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:20:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:20:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:20:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:20:43 INFO - 'talos_options': ['--disable-e10s'], 16:20:43 INFO - 'tests': ['damp', 'tps']}, 16:20:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:20:43 INFO - 'tests': ['damp', 'tps']}, 16:20:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:20:43 INFO - 'tests': ['dromaeo_dom']}, 16:20:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:20:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:20:43 INFO - 'tests': ['a11yr', 16:20:43 INFO - 'ts_paint', 16:20:43 INFO - 'tpaint', 16:20:43 INFO - 'sessionrestore', 16:20:43 INFO - 'sessionrestore_no_auto_restore', 16:20:43 INFO - 'tabpaint']}, 16:20:43 INFO - 'other-e10s': {'tests': ['a11yr', 16:20:43 INFO - 'ts_paint', 16:20:43 INFO - 'tpaint', 16:20:43 INFO - 'sessionrestore', 16:20:43 INFO - 'sessionrestore_no_auto_restore', 16:20:43 INFO - 'tabpaint']}, 16:20:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:20:43 INFO - 'ts_paint', 16:20:43 INFO - 'tpaint', 16:20:43 INFO - 'sessionrestore', 16:20:43 INFO - 'sessionrestore_no_auto_restore', 16:20:43 INFO - 'tabpaint']}, 16:20:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:20:43 INFO - 'ts_paint', 16:20:43 INFO - 'tpaint', 16:20:43 INFO - 'sessionrestore', 16:20:43 INFO - 'sessionrestore_no_auto_restore', 16:20:43 INFO - 'tabpaint']}, 16:20:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:20:43 INFO - 'tests': ['a11yr', 16:20:43 INFO - 'ts_paint', 16:20:43 INFO - 'tpaint', 16:20:43 INFO - 'sessionrestore', 16:20:43 INFO - 'sessionrestore_no_auto_restore', 16:20:43 INFO - 'tabpaint']}, 16:20:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:20:43 INFO - 'tests': ['a11yr', 16:20:43 INFO - 'ts_paint', 16:20:43 INFO - 'tpaint', 16:20:43 INFO - 'sessionrestore', 16:20:43 INFO - 'sessionrestore_no_auto_restore', 16:20:43 INFO - 'tabpaint']}, 16:20:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:20:43 INFO - 'tests': ['tsvgx', 16:20:43 INFO - 'tsvgr_opacity', 16:20:43 INFO - 'tart', 16:20:43 INFO - 'tscrollx', 16:20:43 INFO - 'cart']}, 16:20:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:20:43 INFO - 'tsvgr_opacity', 16:20:43 INFO - 'tart', 16:20:43 INFO - 'tscrollx', 16:20:43 INFO - 'cart']}, 16:20:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:20:43 INFO - 'talos_options': ['--disable-e10s'], 16:20:43 INFO - 'tests': ['tp5o']}, 16:20:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:20:43 INFO - 'tests': ['tp5o']}, 16:20:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:20:43 INFO - 'talos_options': ['--disable-e10s', 16:20:43 INFO - '--xperf_path', 16:20:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:20:43 INFO - 'tests': ['tp5n']}, 16:20:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:20:43 INFO - 'talos_options': ['--xperf_path', 16:20:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:20:43 INFO - 'tests': ['tp5n']}}, 16:20:43 INFO - 'talos.zip': {'path': '', 16:20:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:20:43 INFO - Running post-action listener: _resource_record_post_action 16:20:43 INFO - ##### 16:20:43 INFO - ##### Running create-virtualenv step. 16:20:43 INFO - ##### 16:20:43 INFO - Running pre-action listener: _resource_record_pre_action 16:20:43 INFO - Running main action method: create_virtualenv 16:20:43 INFO - Creating virtualenv /builds/slave/test/build/venv 16:20:43 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 16:20:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:20:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:20:43 INFO - Using real prefix '/tools/python27' 16:20:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:20:44 INFO - Installing distribute.............................................................................................................................................................................................done. 16:20:47 INFO - Installing pip.................done. 16:20:47 INFO - Return code: 0 16:20:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:20:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:20:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:20:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:20:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:20:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:20:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:20:47 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 0x10e710a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e720420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc00848dd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5afe00>, '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 0x10e418db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5aab20>, '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.OEZMWScpxd/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.yycfIiOqIF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 16:20:47 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 16:20:47 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 16:20:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OEZMWScpxd/Render', 16:20:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:20:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:20:47 INFO - 'HOME': '/Users/cltbld', 16:20:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:20:47 INFO - 'LOGNAME': 'cltbld', 16:20:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:20:47 INFO - 'MOZ_NO_REMOTE': '1', 16:20:47 INFO - 'NO_EM_RESTART': '1', 16:20:47 INFO - 'PAGER': '/bin/cat', 16:20:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:20:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:20:47 INFO - 'PWD': '/builds/slave/test', 16:20:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:20:47 INFO - 'SHELL': '/bin/bash', 16:20:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yycfIiOqIF/Listeners', 16:20:47 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 16:20:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:20:47 INFO - 'USER': 'cltbld', 16:20:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:20:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:20:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:20:47 INFO - 'XPC_FLAGS': '0x0', 16:20:47 INFO - 'XPC_SERVICE_NAME': '0', 16:20:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:20:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:20:47 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 16:20:47 INFO - Cleaning up... 16:20:47 INFO - Return code: 0 16:20:47 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 16:20:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:20:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:20:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:20:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:20:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:20:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:20:47 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 0x10e710a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e720420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc00848dd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5afe00>, '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 0x10e418db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5aab20>, '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.OEZMWScpxd/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.yycfIiOqIF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 16:20:47 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 16:20:47 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 16:20:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OEZMWScpxd/Render', 16:20:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:20:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:20:47 INFO - 'HOME': '/Users/cltbld', 16:20:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:20:47 INFO - 'LOGNAME': 'cltbld', 16:20:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:20:47 INFO - 'MOZ_NO_REMOTE': '1', 16:20:47 INFO - 'NO_EM_RESTART': '1', 16:20:47 INFO - 'PAGER': '/bin/cat', 16:20:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:20:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:20:47 INFO - 'PWD': '/builds/slave/test', 16:20:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:20:47 INFO - 'SHELL': '/bin/bash', 16:20:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yycfIiOqIF/Listeners', 16:20:47 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 16:20:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:20:47 INFO - 'USER': 'cltbld', 16:20:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:20:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:20:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:20:47 INFO - 'XPC_FLAGS': '0x0', 16:20:47 INFO - 'XPC_SERVICE_NAME': '0', 16:20:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:20:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:20:47 INFO - Downloading/unpacking psutil>=0.7.1 16:20:47 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 16:20:47 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 16:20:47 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 16:20:47 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 16:20:47 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 16:20:47 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 16:20:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:20:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:20:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:20:50 INFO - Installing collected packages: psutil 16:20:50 INFO - Running setup.py install for psutil 16:20:50 INFO - building 'psutil._psutil_osx' extension 16:20:50 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 16:20:51 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 16:20:51 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 16:20:51 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 16:20:51 INFO - building 'psutil._psutil_posix' extension 16:20:51 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 16:20:51 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:20:51 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:20:51 INFO - ^ 16:20:51 INFO - 1 warning generated. 16:20:51 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 16:20:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:20:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:20:51 INFO - Successfully installed psutil 16:20:51 INFO - Cleaning up... 16:20:51 INFO - Return code: 0 16:20:51 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 16:20:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:20:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:20:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:20:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:20:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:20:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:20:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e710a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e720420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc00848dd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5afe00>, '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 0x10e418db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5aab20>, '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.OEZMWScpxd/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.yycfIiOqIF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 16:20:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 16:20:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 16:20:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OEZMWScpxd/Render', 16:20:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:20:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:20:51 INFO - 'HOME': '/Users/cltbld', 16:20:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:20:51 INFO - 'LOGNAME': 'cltbld', 16:20:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:20:51 INFO - 'MOZ_NO_REMOTE': '1', 16:20:51 INFO - 'NO_EM_RESTART': '1', 16:20:51 INFO - 'PAGER': '/bin/cat', 16:20:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:20:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:20:51 INFO - 'PWD': '/builds/slave/test', 16:20:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:20:51 INFO - 'SHELL': '/bin/bash', 16:20:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yycfIiOqIF/Listeners', 16:20:51 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 16:20:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:20:51 INFO - 'USER': 'cltbld', 16:20:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:20:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:20:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:20:51 INFO - 'XPC_FLAGS': '0x0', 16:20:51 INFO - 'XPC_SERVICE_NAME': '0', 16:20:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:20:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:20:51 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 16:20:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:20:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:20:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:20:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:20:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:20:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:20:54 INFO - Downloading mozsystemmonitor-0.0.tar.gz 16:20:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:20:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 16:20:54 INFO - Installing collected packages: mozsystemmonitor 16:20:54 INFO - Running setup.py install for mozsystemmonitor 16:20:54 INFO - Successfully installed mozsystemmonitor 16:20:54 INFO - Cleaning up... 16:20:54 INFO - Return code: 0 16:20:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:20:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:20:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:20:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:20:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:20:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:20:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:20:54 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e710a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e720420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc00848dd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5afe00>, '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 0x10e418db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5aab20>, '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.OEZMWScpxd/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.yycfIiOqIF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 16:20:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:20:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:20:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OEZMWScpxd/Render', 16:20:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:20:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:20:54 INFO - 'HOME': '/Users/cltbld', 16:20:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:20:54 INFO - 'LOGNAME': 'cltbld', 16:20:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:20:54 INFO - 'MOZ_NO_REMOTE': '1', 16:20:54 INFO - 'NO_EM_RESTART': '1', 16:20:54 INFO - 'PAGER': '/bin/cat', 16:20:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:20:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:20:54 INFO - 'PWD': '/builds/slave/test', 16:20:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:20:54 INFO - 'SHELL': '/bin/bash', 16:20:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yycfIiOqIF/Listeners', 16:20:54 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 16:20:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:20:54 INFO - 'USER': 'cltbld', 16:20:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:20:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:20:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:20:54 INFO - 'XPC_FLAGS': '0x0', 16:20:54 INFO - 'XPC_SERVICE_NAME': '0', 16:20:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:20:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:20:54 INFO - Downloading/unpacking blobuploader==1.2.4 16:20:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:20:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:20:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:20:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:20:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:20:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:20:57 INFO - Downloading blobuploader-1.2.4.tar.gz 16:20:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:20:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:20:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:20:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:20:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:20:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:20:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:20:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:20:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:20:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:20:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:20:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:20:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:20:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:20:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:20:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:20:58 INFO - Downloading docopt-0.6.1.tar.gz 16:20:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:20:58 INFO - Installing collected packages: blobuploader, requests, docopt 16:20:58 INFO - Running setup.py install for blobuploader 16:20:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:20:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:20:58 INFO - Running setup.py install for requests 16:20:59 INFO - Running setup.py install for docopt 16:20:59 INFO - Successfully installed blobuploader requests docopt 16:20:59 INFO - Cleaning up... 16:20:59 INFO - Return code: 0 16:20:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:20:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:20:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:20:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:20:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:20:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:20:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:20:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e710a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e720420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc00848dd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5afe00>, '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 0x10e418db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5aab20>, '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.OEZMWScpxd/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.yycfIiOqIF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 16:20:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:20:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:20:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OEZMWScpxd/Render', 16:20:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:20:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:20:59 INFO - 'HOME': '/Users/cltbld', 16:20:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:20:59 INFO - 'LOGNAME': 'cltbld', 16:20:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:20:59 INFO - 'MOZ_NO_REMOTE': '1', 16:20:59 INFO - 'NO_EM_RESTART': '1', 16:20:59 INFO - 'PAGER': '/bin/cat', 16:20:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:20:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:20:59 INFO - 'PWD': '/builds/slave/test', 16:20:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:20:59 INFO - 'SHELL': '/bin/bash', 16:20:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yycfIiOqIF/Listeners', 16:20:59 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 16:20:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:20:59 INFO - 'USER': 'cltbld', 16:20:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:20:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:20:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:20:59 INFO - 'XPC_FLAGS': '0x0', 16:20:59 INFO - 'XPC_SERVICE_NAME': '0', 16:20:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:20:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:20:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:20:59 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-gsMLxC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:20:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:20:59 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-eGk2Qd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:21:00 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-U7XGcc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:21:00 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-QhPnaH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:21:00 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-jE_LLG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:21:00 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-t4BaK9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:21:00 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-nol69z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:21:00 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-a2xs6X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:21:00 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-Yla3bl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:21:00 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-vLp8bS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:21:00 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-hnh3yj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:21:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:21:00 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-phIeZj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:21:01 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-h6QgJY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:21:01 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-2ITE4b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:21:01 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-ofXFWW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:21:01 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-6vfy9o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:21:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:21:01 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-MOZOQ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:21:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:21:01 INFO - Running setup.py install for manifestparser 16:21:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:21:01 INFO - Running setup.py install for mozcrash 16:21:01 INFO - Running setup.py install for mozdebug 16:21:02 INFO - Running setup.py install for mozdevice 16:21:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:21:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:21:02 INFO - Running setup.py install for mozfile 16:21:02 INFO - Running setup.py install for mozhttpd 16:21:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:21:02 INFO - Running setup.py install for mozinfo 16:21:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:21:02 INFO - Running setup.py install for mozInstall 16:21:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:21:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:21:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:21:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:21:02 INFO - Running setup.py install for mozleak 16:21:02 INFO - Running setup.py install for mozlog 16:21:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:21:03 INFO - Running setup.py install for moznetwork 16:21:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:21:03 INFO - Running setup.py install for mozprocess 16:21:03 INFO - Running setup.py install for mozprofile 16:21:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:21:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:21:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:21:03 INFO - Running setup.py install for mozrunner 16:21:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:21:03 INFO - Running setup.py install for mozscreenshot 16:21:03 INFO - Running setup.py install for moztest 16:21:04 INFO - Running setup.py install for mozversion 16:21:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:21:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:21:04 INFO - Cleaning up... 16:21:04 INFO - Return code: 0 16:21:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:21:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:21:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:21:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:21:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:21:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:21:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:21:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e710a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e720420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc00848dd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5afe00>, '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 0x10e418db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5aab20>, '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.OEZMWScpxd/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.yycfIiOqIF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 16:21:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:21:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:21:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OEZMWScpxd/Render', 16:21:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:21:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:21:04 INFO - 'HOME': '/Users/cltbld', 16:21:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:21:04 INFO - 'LOGNAME': 'cltbld', 16:21:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:21:04 INFO - 'MOZ_NO_REMOTE': '1', 16:21:04 INFO - 'NO_EM_RESTART': '1', 16:21:04 INFO - 'PAGER': '/bin/cat', 16:21:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:21:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:21:04 INFO - 'PWD': '/builds/slave/test', 16:21:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:21:04 INFO - 'SHELL': '/bin/bash', 16:21:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yycfIiOqIF/Listeners', 16:21:04 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 16:21:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:21:04 INFO - 'USER': 'cltbld', 16:21:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:21:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:21:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:21:04 INFO - 'XPC_FLAGS': '0x0', 16:21:04 INFO - 'XPC_SERVICE_NAME': '0', 16:21:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:21:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:21:04 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-dhVTYy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:21:04 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 16:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:21:04 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-P9IJt5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:21:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:21:04 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-iuSdHe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:21:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:21:04 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-6O11IA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:21:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:21:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:21:04 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-EDxntB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:21:05 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)) 16:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:21:05 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-xOwxP5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:21:05 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)) 16:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:21:05 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-MU5i4y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:21:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 16:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:21:05 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-FJ6BgI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:21:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:21:05 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-HToL6e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:21:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 16:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:21:05 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-OE9y7Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:21:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:21:05 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-rUKmdP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:21:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 16:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:21:05 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-XtURM8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:21:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 16:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:21:05 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-mY5MQc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:21:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 16:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:21:05 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-PnCReW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:21:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 16:21:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:21:05 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-ItGsMI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:21:06 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)) 16:21:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:21:06 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-bJBcya-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:21:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:21:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:21:06 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-LkkQAJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:21:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 16:21:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:21:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:21:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:21:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:21:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:21:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:21:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:21:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:21:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:21:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:21:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:21:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:21:08 INFO - Downloading blessings-1.6.tar.gz 16:21:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:21:08 INFO - Installing collected packages: blessings 16:21:08 INFO - Running setup.py install for blessings 16:21:08 INFO - Successfully installed blessings 16:21:08 INFO - Cleaning up... 16:21:09 INFO - Return code: 0 16:21:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:21:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:21:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:21:09 INFO - Reading from file tmpfile_stdout 16:21:09 INFO - Current package versions: 16:21:09 INFO - blessings == 1.6 16:21:09 INFO - blobuploader == 1.2.4 16:21:09 INFO - docopt == 0.6.1 16:21:09 INFO - manifestparser == 1.1 16:21:09 INFO - mozInstall == 1.12 16:21:09 INFO - mozcrash == 0.17 16:21:09 INFO - mozdebug == 0.1 16:21:09 INFO - mozdevice == 0.48 16:21:09 INFO - mozfile == 1.2 16:21:09 INFO - mozhttpd == 0.7 16:21:09 INFO - mozinfo == 0.9 16:21:09 INFO - mozleak == 0.1 16:21:09 INFO - mozlog == 3.1 16:21:09 INFO - moznetwork == 0.27 16:21:09 INFO - mozprocess == 0.22 16:21:09 INFO - mozprofile == 0.28 16:21:09 INFO - mozrunner == 6.11 16:21:09 INFO - mozscreenshot == 0.1 16:21:09 INFO - mozsystemmonitor == 0.0 16:21:09 INFO - moztest == 0.7 16:21:09 INFO - mozversion == 1.4 16:21:09 INFO - psutil == 3.1.1 16:21:09 INFO - requests == 1.2.3 16:21:09 INFO - wsgiref == 0.1.2 16:21:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:21:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:21:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:21:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:21:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:21:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:21:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:21:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e710a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e720420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc00848dd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5afe00>, '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 0x10e418db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5aab20>, '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.OEZMWScpxd/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.yycfIiOqIF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 16:21:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 16:21:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:21:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OEZMWScpxd/Render', 16:21:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:21:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:21:09 INFO - 'HOME': '/Users/cltbld', 16:21:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:21:09 INFO - 'LOGNAME': 'cltbld', 16:21:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:21:09 INFO - 'MOZ_NO_REMOTE': '1', 16:21:09 INFO - 'NO_EM_RESTART': '1', 16:21:09 INFO - 'PAGER': '/bin/cat', 16:21:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:21:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:21:09 INFO - 'PWD': '/builds/slave/test', 16:21:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:21:09 INFO - 'SHELL': '/bin/bash', 16:21:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yycfIiOqIF/Listeners', 16:21:09 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 16:21:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:21:09 INFO - 'USER': 'cltbld', 16:21:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:21:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:21:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:21:09 INFO - 'XPC_FLAGS': '0x0', 16:21:09 INFO - 'XPC_SERVICE_NAME': '0', 16:21:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:21:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:21:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:21:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 16:21:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 16:21:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:21:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 16:21:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 16:21:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 16:21:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 16:21:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 16:21:09 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:21:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:21:09 INFO - Cleaning up... 16:21:09 INFO - Return code: 0 16:21:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:21:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:21:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:21:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:21:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:21:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:21:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:21:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e710a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e720420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc00848dd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5afe00>, '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 0x10e418db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5aab20>, '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.OEZMWScpxd/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.yycfIiOqIF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 16:21:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:21:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:21:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OEZMWScpxd/Render', 16:21:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:21:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:21:09 INFO - 'HOME': '/Users/cltbld', 16:21:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:21:09 INFO - 'LOGNAME': 'cltbld', 16:21:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:21:09 INFO - 'MOZ_NO_REMOTE': '1', 16:21:09 INFO - 'NO_EM_RESTART': '1', 16:21:09 INFO - 'PAGER': '/bin/cat', 16:21:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:21:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:21:09 INFO - 'PWD': '/builds/slave/test', 16:21:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:21:09 INFO - 'SHELL': '/bin/bash', 16:21:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yycfIiOqIF/Listeners', 16:21:09 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 16:21:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:21:09 INFO - 'USER': 'cltbld', 16:21:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:21:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:21:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:21:09 INFO - 'XPC_FLAGS': '0x0', 16:21:09 INFO - 'XPC_SERVICE_NAME': '0', 16:21:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:21:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:21:09 INFO - Downloading/unpacking jsonschema 16:21:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:21:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:21:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:21:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:21:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:21:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:21:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:21:12 INFO - Downloading/unpacking functools32 (from jsonschema) 16:21:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:21:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:21:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:21:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:21:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:21:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:21:12 INFO - Downloading functools32-3.2.3-2.tar.gz 16:21:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:21:12 INFO - warning: no files found matching '*.txt' 16:21:12 INFO - no previously-included directories found matching 'build' 16:21:12 INFO - no previously-included directories found matching 'dist' 16:21:12 INFO - no previously-included directories found matching '.git*' 16:21:12 INFO - Installing collected packages: jsonschema, functools32 16:21:12 INFO - Running setup.py install for functools32 16:21:12 INFO - warning: no files found matching '*.txt' 16:21:12 INFO - no previously-included directories found matching 'build' 16:21:12 INFO - no previously-included directories found matching 'dist' 16:21:12 INFO - no previously-included directories found matching '.git*' 16:21:12 INFO - Successfully installed jsonschema functools32 16:21:12 INFO - Cleaning up... 16:21:13 INFO - Return code: 0 16:21:13 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 16:21:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:21:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:21:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:21:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:21:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:21:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:21:13 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 0x10e710a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e720420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc00848dd10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e5afe00>, '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 0x10e418db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5aab20>, '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.OEZMWScpxd/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.yycfIiOqIF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 16:21:13 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 16:21:13 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 16:21:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OEZMWScpxd/Render', 16:21:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:21:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:21:13 INFO - 'HOME': '/Users/cltbld', 16:21:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:21:13 INFO - 'LOGNAME': 'cltbld', 16:21:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:21:13 INFO - 'MOZ_NO_REMOTE': '1', 16:21:13 INFO - 'NO_EM_RESTART': '1', 16:21:13 INFO - 'PAGER': '/bin/cat', 16:21:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:21:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:21:13 INFO - 'PWD': '/builds/slave/test', 16:21:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:21:13 INFO - 'SHELL': '/bin/bash', 16:21:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yycfIiOqIF/Listeners', 16:21:13 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 16:21:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:21:13 INFO - 'USER': 'cltbld', 16:21:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:21:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:21:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:21:13 INFO - 'XPC_FLAGS': '0x0', 16:21:13 INFO - 'XPC_SERVICE_NAME': '0', 16:21:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:21:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:21:13 INFO - Downloading/unpacking flake8 16:21:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:21:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:21:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:21:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:21:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:21:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:21:15 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:21:15 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:21:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:21:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:21:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:21:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:21:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:21:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:21:16 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:21:16 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:21: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 16:21: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 16:21: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 16:21: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 16:21: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 16:21: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 16:21:16 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:21: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 16:21: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 16:21: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 16:21: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 16:21: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 16:21: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 16:21:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 16:21:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:21:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:21:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:21:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:21:16 INFO - no previously-included directories found matching 'docs/_build' 16:21:16 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:21:17 INFO - Running setup.py install for pep8 16:21:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:21:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:21:17 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:21:17 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:21:17 INFO - no previously-included directories found matching 'docs/_build' 16:21:17 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 16:21:17 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:21:17 INFO - Cleaning up... 16:21:17 INFO - Return code: 0 16:21:17 INFO - Running post-action listener: _resource_record_post_action 16:21:17 INFO - Running post-action listener: _start_resource_monitoring 16:21:17 INFO - Starting resource monitoring. 16:21:17 INFO - ##### 16:21:17 INFO - ##### Running install step. 16:21:17 INFO - ##### 16:21:17 INFO - Running pre-action listener: _resource_record_pre_action 16:21:17 INFO - Running main action method: install 16:21:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:21:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:21:17 INFO - Reading from file tmpfile_stdout 16:21:17 INFO - Detecting whether we're running mozinstall >=1.0... 16:21:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:21:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:21:17 INFO - Reading from file tmpfile_stdout 16:21:17 INFO - Output received: 16:21:17 INFO - Usage: mozinstall [options] installer 16:21:17 INFO - Options: 16:21:17 INFO - -h, --help show this help message and exit 16:21:17 INFO - -d DEST, --destination=DEST 16:21:17 INFO - Directory to install application into. [default: 16:21:17 INFO - "/builds/slave/test"] 16:21:17 INFO - --app=APP Application being installed. [default: firefox] 16:21:17 INFO - mkdir: /builds/slave/test/build/application 16:21:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:21:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 16:21:51 INFO - Reading from file tmpfile_stdout 16:21:51 INFO - Output received: 16:21:51 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:21:51 INFO - Running post-action listener: _resource_record_post_action 16:21:51 INFO - ##### 16:21:51 INFO - ##### Running run-tests step. 16:21:51 INFO - ##### 16:21:51 INFO - Running pre-action listener: _resource_record_pre_action 16:21:51 INFO - Running main action method: run_tests 16:21:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:21:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:21:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:21:51 INFO - 2016-04-21 16:21:51.287 screenresolution[1642:7499] starting screenresolution argv=screenresolution get 16:21:51 INFO - 2016-04-21 16:21:51.290 screenresolution[1642:7499] Display 0: 1600x1200x32@60 16:21:51 INFO - 2016-04-21 16:21:51.296 screenresolution[1643:7502] starting screenresolution argv=screenresolution list 16:21:51 INFO - Available Modes on Display 0 16:21:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:21:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:21:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:21:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:21:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:21:51 INFO - Intel Iris: 16:21:51 INFO - Chipset Model: Intel Iris 16:21:51 INFO - Type: GPU 16:21:51 INFO - Bus: Built-In 16:21:51 INFO - VRAM (Dynamic, Max): 1536 MB 16:21:51 INFO - Vendor: Intel (0x8086) 16:21:51 INFO - Device ID: 0x0a2e 16:21:51 INFO - Revision ID: 0x0009 16:21:51 INFO - Displays: 16:21:51 INFO - Display: 16:21:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:21:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:21:51 INFO - Display Serial Number: 1600x1200 60 16:21:51 INFO - Main Display: Yes 16:21:51 INFO - Mirror: Off 16:21:51 INFO - Online: Yes 16:21:51 INFO - Rotation: Supported 16:21:51 INFO - Return code: 0 16:21:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:21:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:21:51 INFO - Python 2.7.3 16:21:51 INFO - Return code: 0 16:21:51 INFO - grabbing minidump binary from tooltool 16:21:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:21:51 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 0x10e5afe00>, '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 0x10e418db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e5aab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:21:51 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 16:21:51 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 16:21:51 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:21:51 INFO - Return code: 0 16:21:51 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:21:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:21:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:21:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:21:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:21:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 16:21:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 16:21:52 INFO - Return code: 0 16:21:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fKS3xRVXT_2xF-eilOH6FA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0166', '--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 16:21:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fKS3xRVXT_2xF-eilOH6FA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0166 --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 16:21:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OEZMWScpxd/Render', 16:21:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:21:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:21:52 INFO - 'HOME': '/Users/cltbld', 16:21:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:21:52 INFO - 'LOGNAME': 'cltbld', 16:21:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:21:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:21:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:21:52 INFO - 'MOZ_NO_REMOTE': '1', 16:21:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:21:52 INFO - 'NO_EM_RESTART': '1', 16:21:52 INFO - 'PAGER': '/bin/cat', 16:21:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:21:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:21:52 INFO - 'PWD': '/builds/slave/test', 16:21:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:21:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:21:52 INFO - 'SHELL': '/bin/bash', 16:21:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yycfIiOqIF/Listeners', 16:21:52 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 16:21:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:21:52 INFO - 'USER': 'cltbld', 16:21:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:21:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:21:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:21:52 INFO - 'XPC_FLAGS': '0x0', 16:21:52 INFO - 'XPC_SERVICE_NAME': '0', 16:21:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:21:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fKS3xRVXT_2xF-eilOH6FA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0166', '--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 16:21:53 INFO - mozversion application_buildid: 20160421142616 16:21:53 INFO - mozversion application_changeset: 6e5771e2760a4093e716784f3d69a737a25b1dbf 16:21:53 INFO - mozversion application_display_name: Nightly 16:21:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:21:53 INFO - mozversion application_name: Firefox 16:21:53 INFO - mozversion application_remotingname: firefox 16:21:53 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:21:53 INFO - mozversion application_vendor: Mozilla 16:21:53 INFO - mozversion application_version: 48.0a1 16:21:53 INFO - mozversion platform_buildid: 20160421142616 16:21:53 INFO - mozversion platform_changeset: 6e5771e2760a4093e716784f3d69a737a25b1dbf 16:21:53 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:21:53 INFO - mozversion platform_version: 48.0a1 16:21:53 INFO - using testdate: 1461280913 16:21:53 INFO - actual date: 1461280913 16:21:53 INFO - starting webserver on 'localhost:49275' 16:21:53 INFO - SUITE-START | Running 2 tests 16:21:53 INFO - TEST-START | dromaeo_css 16:21:53 INFO - operating with platform_type : mac_ 16:21:53 INFO - Initialising browser for dromaeo_css test... 16:21:53 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/tmpFQYQL5/profile http://localhost:49275/getInfo.html) 16:21:56 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 16:21:56 INFO - PROCESS | 1661 | colorDepth:24 16:21:56 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 16:21:56 INFO - PROCESS | 1661 | __metrics 16:21:56 ERROR - PROCESS | 1661 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 16:21:56 INFO - TEST-INFO | 1661: exit 0 16:21:56 INFO - Browser initialized. 16:21:56 INFO - Running cycle 1/1 for dromaeo_css test... 16:21:56 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/tmpFQYQL5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:22:08 INFO - PROCESS | 1664 | RSS: Main: 247853056 16:22:08 INFO - PROCESS | 1664 | 16:22:58 INFO - PROCESS | 1664 | 1461280978283 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] 16:25:09 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 16:25:10 INFO - PROCESS | 1664 | RSS: Main: 260116480 16:25:10 INFO - PROCESS | 1664 | 16:28:12 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 16:28:13 INFO - PROCESS | 1664 | RSS: Main: 271863808 16:28:13 INFO - PROCESS | 1664 | 16:31:15 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 16:31:15 INFO - PROCESS | 1664 | RSS: Main: 273883136 16:31:15 INFO - PROCESS | 1664 | 16:34:17 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 16:34:18 INFO - PROCESS | 1664 | RSS: Main: 275488768 16:34:18 INFO - PROCESS | 1664 | 16:37:20 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 16:37:21 INFO - PROCESS | 1664 | RSS: Main: 278355968 16:37:21 INFO - PROCESS | 1664 | 16:40:24 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 16:40:24 INFO - PROCESS | 1664 | RSS: Main: 279883776 16:40:24 INFO - PROCESS | 1664 | 16:40:24 INFO - PROCESS | 1664 | __start_tp_report 16:40:24 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 16:40:24 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 16:40:24 INFO - PROCESS | 1664 | |i|pagename|runs| 16:40:24 INFO - PROCESS | 1664 | |0;dojo.html;3416.658334166583;3421.98289008555;3449.758516903816;3471;3510.947335789962;5675.318961724589;5701.6864072476;5731;5736.569757268206;5751.194832723417;4141.896452588687;4405.955940440603;4719.787609557569;5241.5806735461165;5381.919271210935;5147.871303217412;5196.58427325815;5213.687178769286;5269.341332333458;5344.5724342052545;430.6907349339731;5191.325127733408;5250.8687282817855;5258.395284542269;5322.121849894763;5192.818251361203;5204.34945631796;5222.764975576109;5227.895442091137;5294.814681486168;5115.565176959925;5315.989961907225;5323.760430780624;5331.653442526263;5378.838634840961;4516.5935065843905;5111.514406131449;5139.820106296299;5170;5235.502627250406;5521.309836270466;5561.77752889884;5581.944180558183;5602.1596760485845;5676.120201368794;6113.633182009094;6120.9347721082095;6184.381561843825;6222.12890195373;6296.716647750863;5242.8689282767855;6246.156768836195;6261.937380626181;6288.056791481269;6320.62076275425;5868.149118377854;5882.411758824126;5889.558283128783;5892.499137573269;5896.086106653475;6198.411150940744;6257.843553911189;6322.209723784527;6330.955392360301;6331;6175.042868355412;6188.381161883865;6200.193974783249;6206.565540412128;6229.813105606839;6260.625065432878;6271.247450305992;6305.779297724557;6313.747450101944;6338.049292606146;6139.293981192131;6147.047207682816;6233.875322493615;6242.531810114168;6260.749570017239;6009.2188015557695;6158.261008678987;6261.029540421242;6289.371062893765;6296.087067375205;4440.7335559866515;4886.560209581155;4961.181405068195;4962.478939711351;4967.180415231519;4351.173277077409;4451.399061126771;4469.865904022885;4488.955110448919;4500.797464114123;5753.453421924911;5827.446392592782;5863.7947671832135;5884.558658100659;5945.078512830518;6223.564350495512;6228.532860035515;6235.407636274637;6244.65654389013;6258.155149054819;5797.304323481125;5823;5842.795502157404;5843.795467158629;5877.059670452707;5336.03951288764;5406.783728650888;5422.91865622016;5521.089020311603;5531;6352.110704501373;6356.809295721136;6358.809235722936;6379.106925030499;6385.840353991094;6163.630182189083;6241.21984751906;6259.968700156653;6268.059791031382;6268.968655156695;6229.470495007794;6307.779227727007;6339.651319177305;6420.229572451336;6438.3883531064475;6246.5002799775;6259.655718935503;6283.02613094971;6296.5592408530965;6297.307296197506;6154.446099851035;6251.812445626639;6271.3728627136925;6274.749010039547;6287.497000239879;5433.9184912225555;5543.3902270751;6190.071489276645;6212.751489940351;6218.098375735583;5657.462541058593;5914.996278776959;6138.754449821957;6149.108379285068;6173.135760993554;5771.884562308646;6010.068439391901;6028;6075.058365953284;6088.452039316483;6040.456358927542;6108.57239993214;6112.533877023262;6130.249081909528;6215.502759779117;6108.75564977396;6110.572259941761;6116.5412594056515;6142.508599312134;6147.170132032118 16:40:24 INFO - PROCESS | 1664 | |1;ext.html;510.9872253193672;528.5718567959952;547.843864498618;586.0915580849681;595.6902410746412;37671.623283767156;38403.23193536128;38420.61579384205;38526.61473385265;38571.99999999996;39622;40257.79871100648;40287.39568906469;40367;40539.59460405395;9250.398724082914;9444.622215111387;9510.857337139949;9516.857247141264;9544.141027307542;9375.203107735817;9585.616575336979;9617.374870633386;9625.71122866315;9628.037196280386;9587.280953928483;9624;9637.855432168522;9649.807003859918;9654.179394751454;9562.713118606418;9621.470819104947;9636.759081022961;9642.42145471274;9645.855312170323;9423.199028082552;9547.570359333846;9548.761280967961;9564.66523671668;9566.13904748576;38485.65300214479;38856.80571597124;38964.415533767016;39052;39239.21521569581;2724.359775452767;3032.9545056824163;3043.6195475565555;3168.493041113411;3356.077078803324;28122.296942576395;28307.726152323194;28639.57040644392;29045.854770726222;29853.850730746424;2686.8790904409348;2740.931476713078;2749.1889892481768;2840.318323602341;2843.536065085818;485490;487914;488568;497255;498838;70104;70175.2982470179;71008.57982840315;71210;71278.64360678164;74582;74613.62693186608;76343.47313053819;76869;77091.61454192693;17223.22495487706;17505.862118962228;17531.73702394465;17546.473605791845;17584.296628134987;12837.165584237182;13089.476420943247;13142.60572182836;13253.734925301633;13273.800892986614;20575.691364629543;20853.582928341653;20914.581708365746;20950.685739713917;21478;7266.854662906818;8801.515916624647;8823.955880220687;8828.85224920769;8838.381313308008;865.1175800683268;916.922061624756;946.3895787217207;993.7664648807524;1153.955670118544;29386.55920161199;29489.557656635166;29736;30197;30283;1078.245228340165;1102.9007389334838;1137.6201623748666;1164.8602167739757;1174.7121955120879;15101;15195.544133676007;15207.923960380127;15505.518057963447;15636.921815391077;451049;490456.09543904103;575712.1214393901;586498;590310;58464.83070338569;58604.70697646485;59568;59574.702126489094;59795.40204597898;1479.4156308258393;1481.6147801571453;1490.3219035338896;1494.2304713072776;1512.0927443534013;28628.71371286344;29258.561121583618;29407.26481837971;29680.406391872475;29934.70065299319;51126.97746044984;51159.74420127876;51605.22592161046;51692;51875.481245188574;543143;558790;561688;563990.9804960766;567584;27324;27761.86119069392;27846.582301265902;27854.88580456759;27872.860635696692;45262;45371.09257814891;45564;45829.31256031096;46627.53372466368;37728.81135594304;37800;37904;38655.6134438652;38896.02759931024;33197.50203746898;36431.27137457183;36802;37521.81239093787;38347.42478862875;36896.81551592332;37137;37838;37978;38137.23725525529;32140.357192855543;33052.33895322032;33160.668393315755;33195.83402082974;33335.666643334225;23738.762612373655;23801.571905685716;24640.630390543796;24684.25947221586;25095.823486187575 16:40:24 INFO - PROCESS | 1664 | |2;jquery.html;349.536863655656;395.9604039596041;396.26492855752576;398.4461598378254;406.37012630422856;17604.20781064852;18467.815321846778;20885.686714699255;21427.464313392175;21430.464238394048;20073.698894516594;20744.48138796531;21124.683129753026;21180.046897889595;21373.786262137375;5795;6014.909776353358;6583.275839657634;7075;7096.645167741593;6780.660966951657;6969.6166710830885;7051.012858199856;7159.713611455535;7185.676644550982;7149.070620819312;7160.892586611205;7167.139943206823;7176.6052867092285;7181.964090179542;5511.338639363282;5754.3094828620415;5764.654120752768;5897;5907.497862681677;5559.63862348946;5645.350784659735;5865.208196893407;5868.178455016301;5877.177195192677;19218.174273444;24055;24367.756322436726;25550.105746298977;25833.74166258351;1889.5559543507266;2117.163720330461;2278.100150440583;2292.965605515902;2436.537057958982;10042.497875106215;10199.0820826126;11246.831297530543;13541.796873046813;14871.958962872603;1524.3902439024369;1716.4678949525562;1735.9218835152449;1745.9214335354814;1765.1791916758768;118557.8144218564;121395;122074.38962805129;124016;126164;9621.951890240503;10275.177985761122;11686.47410866513;11792.23350482216;11868.347240901661;15051;15056.924715376572;15076.623084422978;15099.773503397459;15119.697606048037;7177.497575169688;8600.483970961637;8760.4305720128;8773.298136149093;8886.86669699955;8485.787855303666;8562.315014798802;8602.053774084845;8671.089535598798;8722.127787221227;4209.894752631209;4563.155816174019;4613.63090952723;4618.399608050931;4645.605123564535;1767.2665843674731;1839.5677015901254;1907.761529808774;1968.8228059474716;1975.387629834756;870.4385671242013;908.1644886704248;968.1625394034251;1003.172382784206;1003.372891942536;19163.137658805386;19969.900150499154;20059.799402005792;20129.993500324927;20461.488462788548;962.932594718377;981.0385821894583;991.8512223166439;1007.219404961161;1014.3508154780947;10233.692989210187;10293.485325733684;10450.68647940563;10464.73838154037;10517.52671129801;561220;596501;608704;612316;613170;126459.7354026448;126549;127465.36267318789;128913.71086289018;131483;870.3602851903719;999.2655398282257;1011.7420057885192;1036.5646428500047;1038.9272750907514;17230.65538689194;17298.567535811704;17532.912335438243;17613;17771.289148433916;32347.51478727868;44683.106337872414;45598.772006139756;45752.313715294964;46616.06767864556;52438.21342679939;67466.6506669854;67739.32260677527;68050.21573166337;69071;7807.102183248772;7929;8017.719379821679;8065.951426314541;8362.958185209034;12261.203021803554;12587.622371328875;12597.43311550964;12807.487700491876;12897.742045158857;10823.56705731762;10888.346699197757;10918.727031824268;10928.344299342065;10986.176036797113;10688;10713.14294856394;10957.178211634318;11057.170712196457;11173.608923687632;10975;11068.667939961813;11228.775424491629;11350.886491135314;11510.136739744385;8036;8297.547929112501;8302.626381812954;8318.750437486886;8491.8726219108;9903.702888913343;10997.285176463503;11188.608398706006;11240.4379781009;11247.831282530933 16:40:24 INFO - PROCESS | 1664 | |3;mootools.html;1346.468145082692;1599.4002249156567;1749.5013921032507;1893.7632795900504;1917.4439412570355;7811.687532498708;7953.164917683646;8549.358798090145;8825.691100811466;8840.60217290222;7136.536125151874;8574.18545238203;8711.30309575234;8717;8729.476231426119;2345.835791494596;2432.8905199266032;2445.2786428003783;2464.6549483072386;2469.8271121021535;2228.7882651148116;2406.3142004528713;2425.1754403502805;2433.2943446400523;2433.5011322678806;2433.586290330648;2451.779339859412;2462.273629279367;2468.1854987853967;2478.6405971134177;2429.829911906167;2436.7319594844553;2443.0960544598524;2451.9754802451926;2460.5817011108156;2416.0215112879314;2417.5406672732124;2433.3794882305074;2442.951140977188;2443.8044956403623;8777.29781617469;8856.468611883243;8858.247049000842;8868.113188681145;8899.688510902151;5208.270842082112;5417.83746487606;5451.972740136313;5452.345718513764;5453.23654688344;3516.3670539302916;3541.610422853484;3571.357155711971;3623.5832879218965;3625.166211771307;1026.1021606094666;1039.6101461951769;1040.0847254416065;1049.9160067194607;1054.204075922681;9661;11470.254433461881;11530.308181509052;11606.535738570446;11631.418429078512;4628.74541900192;4700;4842.15262329091;4847.3698419205275;4903.509649035068;8688.304935605136;9800.754981125387;9905.365614673497;9944.80110397788;9944.950275248577;5380;5393.865153371119;5402.35228255378;5402.702851343135;5417.945820541823;4386.978065109654;4774.809007639656;4861.197902346144;4876.024795041006;4910.656254062253;3974.82113304902;4002.539707933625;4064.288749468831;4087.4073259377224;4141.855035073758;1158.8783177766213;1351.8310211223597;1378.3383975691725;1379.8551152129082;1386.3553447646893;896.2874514760781;918.9310801689767;919.8114386550741;941.3222479814523;957.6169532187041;5730.74211660468;5878.147668588031;5896.616719913192;5914.615549989237;5965.373635768268;434.8673654535334;442.1643094551301;450.27730492559624;455.2192989023774;468.08255818595256;3272.7709060365546;3298.3238436120537;3879.689624830007;3956.4263181838473;3966.841326346914;10437.634682786065;10722.303050301705;10769.138468922467;10776.245662803685;10932.890671093346;10550;10723.410212438392;10784.083352914933;10842.837357439645;10916.235863489639;6624.8012559623285;7621.504602200839;8042;8072.959635201903;8124.431289809657;5766.163906233489;5846.795362162304;5945.64326140433;6031.909521357271;6070;9656.130948214695;9800.068993445611;9824.901750982399;9848.212143028397;9926.652567160114;8748.912510875065;9933.552990115317;9971.601135954481;10012;10101.040401161878;6859.142607174103;7269.890951635624;7467;7525.661345239368;7636.350910172698;8233.876491852507;8253;8314.293285070837;8326.417150799385;8354.498730076215;7260.382867456326;7392.593407362647;7401.33387995083;7500.474966752275;7555.168931417649;7028.226895041644;7062.435005199674;7113.608751518716;7130.108736407949;7151.499395042297;6887.449004079762;6903.447724182154;6930.826729331606;6969.512134150561;7008.894866577108;4786.880327991828;4844.636652251166;4880.341153944172;4952.8018879245865;5076.36545431821;4908.509149085063;4912.410510738734;4932.383452068491;5005.949940500694;5095.827959569394 16:40:24 INFO - PROCESS | 1664 | |4;prototype.html;315.16324159356907;316.6074068155488;323.574499533114;325.85336598530665;328.8963976347452;6174.444300012998;6181.350958149392;6252.906206406908;6309.1482649842255;6312.6212427254395;5337.733113344337;5814.186013958049;6137.38626137386;6183;6210.2547694276755;3834.4662135146027;4067.430559721641;4322.200392927319;4347.93478097828;4447.822087116511;3558.252766918937;4135.483064616923;4534.523874993111;4584.404027476424;4649.721016738989;4581.633469322463;4613.97784451185;4621.838235661752;4628.282616194495;4646.070785842828;4486.730796152225;4602.884927876813;4613;4620.330052142454;4657.673962822604;3992.3612222044626;4006.3389540725743;4010.278149933011;4029.214303210875;4034.798260087013;5657.20799088128;5944.840756052571;5997.352052159611;6001.729922153513;6002.549808764316;4519.141363140992;4868.123737727207;5387.4073851876265;5400.891982160374;5426.158945363475;5605.579581531401;6180.907286390708;6475.93524064758;6521.902171467432;6585.769498067544;4126.607972242632;4183.912182832485;4302.182585308813;4429.534898835608;4432.645388368923;6055.394460553954;6724.596524208514;6896.689648965808;6906.551074180162;6921.273266307016;4099.1801639672185;4244.957550424456;4473.329000649928;4508.18852606534;4535.319702044654;3686.907827304285;3876.980615096963;4540.72755634663;4608.239640459286;4620.306953956933;4229.471316085489;5172.19830926207;5367.463253674679;5399.568034557226;5439.728013599304;5733.971330143323;6222.3466536013975;6393.680315984182;6433.3594933291715;6606.405423511907;1348.7774517204552;1513.448715067065;1582.8812839037118;1604.3502381535498;1607.6141725985913;977.953589659058;1030.845373194012;1039.932404393712;1050.1965996013057;1055.171690223171;5207.6875387476875;5440.6735595864375;5616.747246373923;6043;6096.268447786294;5637.8160586277345;5805.274340707411;6745.2242992056545;6751.831204219934;6784.728610855609;4872.220444728827;5271.762770675329;5702.572307076985;6031.155638210654;6083.513318934474;2631.973680263211;3294.258791771832;3335.93328133436;3556.4309710446723;3573.6783689468075;6158.568900177034;6754.290799465985;6758.087658166183;7062.081929349151;7070.575765454089;5713.514351280104;6638.369354911276;6882.621455819878;6943.583384996917;6962.126511442011;6558.983357579583;6769.93230067693;6996.545224560489;7001.474889383215;7085.610291434021;5876.41235876409;6705.631190284581;6741.1236539249585;6898.068760717239;6923.134608173978;6592.934070659424;6697.430718388991;6717.126773519607;6736.393724564813;6749.966250168718;6378.617282963037;6585.46459613062;7201.279872012757;7202.747903823341;7252.419806415369;4364.388985541963;4375.540568240416;4391.8682439526865;4399.67002474822;4409.80155892992;6000.729967151576;6393.424591786761;6815.659217039029;6824.761133360308;6844.5208835381045;5071.906137256197;5552.861178470571;5982.790602328898;6178.474829639351;6640.646473530836;5352.798053527962;5472.233887255867;5699.857503562278;6396.264429590657;6596.30738772422;5384.9730751346;6098.237720284965;6270.435660790551;6418.84460797061;6496.906948622526;5042.4705405931845;6334.90497642545;6590.90113648286;6596.142501474777;6638.767643132467;5000.349954505891;6230.847293250763;6405.306454754699;6444.9355506444335;6466.321036291121 16:40:24 INFO - PROCESS | 1664 | |5;yui.html;691.3535843985874;716.9283071692835;736.8306378932979;747.4879707400431;759.9278068583484;14254.287285635703;14397.208153551564;14844.703105937875;14898.627534311647;14935;11489.885101148986;11501.539938402475;13914;13929.791053134186;13959;727.08387431836;785.1755656560617;908.4276905549494;918.8024574716435;927.6104036304769;855.4054931822386;938.8591711243334;939.9436033837958;940.501534186882;941.7174847545743;976.9951150244239;983.8868530119021;985.3595163143942;985.8373368394207;988.298308201178;940.116290686759;942.3874481586989;944.7968686732316;958.9184919281904;984.5421878826344;720.2617317249808;722.743426083738;724.5000949344987;724.5435375713267;725.4595326481805;12551.623451296384;15181.468648597245;15300.617484562976;15326.463573774976;15338;12471.875281247252;12569.434375453127;12595.622131336075;12610.243385396905;12834.844863962287;11090.390028548427;11110.94572535716;11112.388818614972;11268.661940141808;11480;4023.6177563131455;4056.6146216109423;4083.8162282697053;4104.445899803518;4221.957780422187;71179.28820711779;73771.63114184448;74121.6293918527;74205.62897185479;80236;9689.031096890254;9740;9795.951020244853;9883.258755593359;9923.751906202402;22029.779702202773;22451.775482245295;22632.434189145075;22677;22696;7675.692972281158;7740.303372696371;7811.336036436966;7953.005874265717;7983.441159118806;4101.5488296287385;4141.544430112685;4159.792010399528;4166.250074986531;4171.478565179353;3613.1328481164333;3644.781313121221;3706.2031663192006;3706.7590606610484;3727.5154229950476;561.6994907724429;658.3054877104701;664.9900251496232;676.3202981004158;678.6606696651675;548.4707257496456;572.7714053355529;589.4812564942823;597.4085655201319;608.1819952164332;7867.803304917308;8022.799430014296;8142.796430089295;8170.264676179054;8242.29940455068;508.7354575620657;562.8114581615205;570.1419363857368;582.3855832097178;596.6658671144173;3727.794971276606;3840.8271627776817;3879.262940041383;3881.3789793633446;3911.3741801311653;80022;83547.7467837983;83832;83936;86074;70829.64585177145;72258;76203;78199.60900195462;78446.21553784503;68640.999999999;69508.6524567384;70108.2989170112;70757.64621176862;71593.64203178951;7392.002079719169;8291.626876790438;8461.280791132576;8464.238218560113;8553.700620478254;35063.64936350604;38910.6108938907;43836.780816095714;44060.89847753832;44324.33513497365;58765;61945.761084778955;62201.68899155475;62854.68572657107;63471;11254;11391.715207119556;11477.655670329903;11541.711457213636;11971.10216733731;11665.35840528779;11845.822312665145;11900.821487677866;11954.70113247141;11981.161318707607;11377.943110284396;11400;11470.942645287054;11501.884981150417;11612.129090318429;11439;11512.251703639236;11737.706557336134;11753.059755219732;11764.882351176379;11393.715157121138;11490.138239631893;11517.884821151682;11546.42267886619;11687.474063666985;7943.523388596703;8159.224873636995;8197.508149511048;8381.790455238668;9039.231665308522;9180.219681326895;10057.849132263169;10060.547275372772;10074.042965918226;10077.69766906994 16:40:24 INFO - PROCESS | 1664 | __end_tp_report 16:40:24 INFO - PROCESS | 1664 | __start_cc_report 16:40:24 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,394 16:40:24 INFO - PROCESS | 1664 | __end_cc_report 16:40:24 INFO - PROCESS | 1664 | __startTimestamp1461282024347__endTimestamp 16:40:24 INFO - PROCESS | 1664 | 16:40:24 INFO - PROCESS | 1664 | ------- Summary: start ------- 16:40:24 INFO - PROCESS | 1664 | Number of tests: 6 16:40:24 INFO - PROCESS | 1664 | 16:40:24 INFO - PROCESS | 1664 | [#0] dojo.html Cycles:165 Average:5705.33 Median:6010.07 stddev:787.75 (13.1%) stddev-sans-first:769.43 16:40:24 INFO - PROCESS | 1664 | Values: 3416.7 3422.0 3449.8 3471.0 3510.9 5675.3 5701.7 5731.0 5736.6 5751.2 4141.9 4406.0 4719.8 5241.6 5381.9 5147.9 5196.6 5213.7 5269.3 5344.6 430.7 5191.3 5250.9 5258.4 5322.1 5192.8 5204.3 5222.8 5227.9 5294.8 5115.6 5316.0 5323.8 5331.7 5378.8 4516.6 5111.5 5139.8 5170.0 5235.5 5521.3 5561.8 5581.9 5602.2 5676.1 6113.6 6120.9 6184.4 6222.1 6296.7 5242.9 6246.2 6261.9 6288.1 6320.6 5868.1 5882.4 5889.6 5892.5 5896.1 6198.4 6257.8 6322.2 6331.0 6331.0 6175.0 6188.4 6200.2 6206.6 6229.8 6260.6 6271.2 6305.8 6313.7 6338.0 6139.3 6147.0 6233.9 6242.5 6260.7 6009.2 6158.3 6261.0 6289.4 6296.1 4440.7 4886.6 4961.2 4962.5 4967.2 4351.2 4451.4 4469.9 4489.0 4500.8 5753.5 5827.4 5863.8 5884.6 5945.1 6223.6 6228.5 6235.4 6244.7 6258.2 5797.3 5823.0 5842.8 5843.8 5877.1 5336.0 5406.8 5422.9 5521.1 5531.0 6352.1 6356.8 6358.8 6379.1 6385.8 6163.6 6241.2 6260.0 6268.1 6269.0 6229.5 6307.8 6339.7 6420.2 6438.4 6246.5 6259.7 6283.0 6296.6 6297.3 6154.4 6251.8 6271.4 6274.7 6287.5 5433.9 5543.4 6190.1 6212.8 6218.1 5657.5 5915.0 6138.8 6149.1 6173.1 5771.9 6010.1 6028.0 6075.1 6088.5 6040.5 6108.6 6112.5 6130.2 6215.5 6108.8 6110.6 6116.5 6142.5 6147.2 16:40:24 INFO - PROCESS | 1664 | 16:40:24 INFO - PROCESS | 1664 | [#1] ext.html Cycles:180 Average:67459.03 Median:38756.21 stddev:141679.85 (365.6%) stddev-sans-first:141988.13 16:40:24 INFO - PROCESS | 1664 | Values: 511.0 528.6 547.8 586.1 595.7 37671.6 38403.2 38420.6 38526.6 38572.0 39622.0 40257.8 40287.4 40367.0 40539.6 9250.4 9444.6 9510.9 9516.9 9544.1 9375.2 9585.6 9617.4 9625.7 9628.0 9587.3 9624.0 9637.9 9649.8 9654.2 9562.7 9621.5 9636.8 9642.4 9645.9 9423.2 9547.6 9548.8 9564.7 9566.1 38485.7 38856.8 38964.4 39052.0 39239.2 2724.4 3033.0 3043.6 3168.5 3356.1 28122.3 28307.7 28639.6 29045.9 29853.9 2686.9 2740.9 2749.2 2840.3 2843.5 485490.0 487914.0 488568.0 497255.0 498838.0 70104.0 70175.3 71008.6 71210.0 71278.6 74582.0 74613.6 76343.5 76869.0 77091.6 17223.2 17505.9 17531.7 17546.5 17584.3 12837.2 13089.5 13142.6 13253.7 13273.8 20575.7 20853.6 20914.6 20950.7 21478.0 7266.9 8801.5 8824.0 8828.9 8838.4 865.1 916.9 946.4 993.8 1154.0 29386.6 29489.6 29736.0 30197.0 30283.0 1078.2 1102.9 1137.6 1164.9 1174.7 15101.0 15195.5 15207.9 15505.5 15636.9 451049.0 490456.1 575712.1 586498.0 590310.0 58464.8 58604.7 59568.0 59574.7 59795.4 1479.4 1481.6 1490.3 1494.2 1512.1 28628.7 29258.6 29407.3 29680.4 29934.7 51127.0 51159.7 51605.2 51692.0 51875.5 543143.0 558790.0 561688.0 563991.0 567584.0 27324.0 27761.9 27846.6 27854.9 27872.9 45262.0 45371.1 45564.0 45829.3 46627.5 37728.8 37800.0 37904.0 38655.6 38896.0 33197.5 36431.3 36802.0 37521.8 38347.4 36896.8 37137.0 37838.0 37978.0 38137.2 32140.4 33052.3 33160.7 33195.8 33335.7 23738.8 23801.6 24640.6 24684.3 25095.8 16:40:24 INFO - PROCESS | 1664 | 16:40:24 INFO - PROCESS | 1664 | [#2] jquery.html Cycles:180 Average:34539.57 Median:21400.63 stddev:99853.12 (466.6%) stddev-sans-first:100100.23 16:40:24 INFO - PROCESS | 1664 | Values: 349.5 396.0 396.3 398.4 406.4 17604.2 18467.8 20885.7 21427.5 21430.5 20073.7 20744.5 21124.7 21180.0 21373.8 5795.0 6014.9 6583.3 7075.0 7096.6 6780.7 6969.6 7051.0 7159.7 7185.7 7149.1 7160.9 7167.1 7176.6 7182.0 5511.3 5754.3 5764.7 5897.0 5907.5 5559.6 5645.4 5865.2 5868.2 5877.2 19218.2 24055.0 24367.8 25550.1 25833.7 1889.6 2117.2 2278.1 2293.0 2436.5 10042.5 10199.1 11246.8 13541.8 14872.0 1524.4 1716.5 1735.9 1745.9 1765.2 118557.8 121395.0 122074.4 124016.0 126164.0 9622.0 10275.2 11686.5 11792.2 11868.3 15051.0 15056.9 15076.6 15099.8 15119.7 7177.5 8600.5 8760.4 8773.3 8886.9 8485.8 8562.3 8602.1 8671.1 8722.1 4209.9 4563.2 4613.6 4618.4 4645.6 1767.3 1839.6 1907.8 1968.8 1975.4 870.4 908.2 968.2 1003.2 1003.4 19163.1 19969.9 20059.8 20130.0 20461.5 962.9 981.0 991.9 1007.2 1014.4 10233.7 10293.5 10450.7 10464.7 10517.5 561220.0 596501.0 608704.0 612316.0 613170.0 126459.7 126549.0 127465.4 128913.7 131483.0 870.4 999.3 1011.7 1036.6 1038.9 17230.7 17298.6 17532.9 17613.0 17771.3 32347.5 44683.1 45598.8 45752.3 46616.1 52438.2 67466.7 67739.3 68050.2 69071.0 7807.1 7929.0 8017.7 8066.0 8363.0 12261.2 12587.6 12597.4 12807.5 12897.7 10823.6 10888.3 10918.7 10928.3 10986.2 10688.0 10713.1 10957.2 11057.2 11173.6 10975.0 11068.7 11228.8 11350.9 11510.1 8036.0 8297.5 8302.6 8318.8 8491.9 9903.7 10997.3 11188.6 11240.4 11247.8 16:40:24 INFO - PROCESS | 1664 | 16:40:24 INFO - PROCESS | 1664 | [#3] mootools.html Cycles:180 Average:5646.01 Median:4922.40 stddev:3133.47 (63.7%) stddev-sans-first:3125.60 16:40:24 INFO - PROCESS | 1664 | Values: 1346.5 1599.4 1749.5 1893.8 1917.4 7811.7 7953.2 8549.4 8825.7 8840.6 7136.5 8574.2 8711.3 8717.0 8729.5 2345.8 2432.9 2445.3 2464.7 2469.8 2228.8 2406.3 2425.2 2433.3 2433.5 2433.6 2451.8 2462.3 2468.2 2478.6 2429.8 2436.7 2443.1 2452.0 2460.6 2416.0 2417.5 2433.4 2443.0 2443.8 8777.3 8856.5 8858.2 8868.1 8899.7 5208.3 5417.8 5452.0 5452.3 5453.2 3516.4 3541.6 3571.4 3623.6 3625.2 1026.1 1039.6 1040.1 1049.9 1054.2 9661.0 11470.3 11530.3 11606.5 11631.4 4628.7 4700.0 4842.2 4847.4 4903.5 8688.3 9800.8 9905.4 9944.8 9945.0 5380.0 5393.9 5402.4 5402.7 5417.9 4387.0 4774.8 4861.2 4876.0 4910.7 3974.8 4002.5 4064.3 4087.4 4141.9 1158.9 1351.8 1378.3 1379.9 1386.4 896.3 918.9 919.8 941.3 957.6 5730.7 5878.1 5896.6 5914.6 5965.4 434.9 442.2 450.3 455.2 468.1 3272.8 3298.3 3879.7 3956.4 3966.8 10437.6 10722.3 10769.1 10776.2 10932.9 10550.0 10723.4 10784.1 10842.8 10916.2 6624.8 7621.5 8042.0 8073.0 8124.4 5766.2 5846.8 5945.6 6031.9 6070.0 9656.1 9800.1 9824.9 9848.2 9926.7 8748.9 9933.6 9971.6 10012.0 10101.0 6859.1 7269.9 7467.0 7525.7 7636.4 8233.9 8253.0 8314.3 8326.4 8354.5 7260.4 7392.6 7401.3 7500.5 7555.2 7028.2 7062.4 7113.6 7130.1 7151.5 6887.4 6903.4 6930.8 6969.5 7008.9 4786.9 4844.6 4880.3 4952.8 5076.4 4908.5 4912.4 4932.4 5005.9 5095.8 16:40:24 INFO - PROCESS | 1664 | 16:40:24 INFO - PROCESS | 1664 | [#4] prototype.html Cycles:180 Average:5216.62 Median:5701.21 stddev:1654.64 (29.0%) stddev-sans-first:1617.86 16:40:24 INFO - PROCESS | 1664 | Values: 315.2 316.6 323.6 325.9 328.9 6174.4 6181.4 6252.9 6309.1 6312.6 5337.7 5814.2 6137.4 6183.0 6210.3 3834.5 4067.4 4322.2 4347.9 4447.8 3558.3 4135.5 4534.5 4584.4 4649.7 4581.6 4614.0 4621.8 4628.3 4646.1 4486.7 4602.9 4613.0 4620.3 4657.7 3992.4 4006.3 4010.3 4029.2 4034.8 5657.2 5944.8 5997.4 6001.7 6002.5 4519.1 4868.1 5387.4 5400.9 5426.2 5605.6 6180.9 6475.9 6521.9 6585.8 4126.6 4183.9 4302.2 4429.5 4432.6 6055.4 6724.6 6896.7 6906.6 6921.3 4099.2 4245.0 4473.3 4508.2 4535.3 3686.9 3877.0 4540.7 4608.2 4620.3 4229.5 5172.2 5367.5 5399.6 5439.7 5734.0 6222.3 6393.7 6433.4 6606.4 1348.8 1513.4 1582.9 1604.4 1607.6 978.0 1030.8 1039.9 1050.2 1055.2 5207.7 5440.7 5616.7 6043.0 6096.3 5637.8 5805.3 6745.2 6751.8 6784.7 4872.2 5271.8 5702.6 6031.2 6083.5 2632.0 3294.3 3335.9 3556.4 3573.7 6158.6 6754.3 6758.1 7062.1 7070.6 5713.5 6638.4 6882.6 6943.6 6962.1 6559.0 6769.9 6996.5 7001.5 7085.6 5876.4 6705.6 6741.1 6898.1 6923.1 6592.9 6697.4 6717.1 6736.4 6750.0 6378.6 6585.5 7201.3 7202.7 7252.4 4364.4 4375.5 4391.9 4399.7 4409.8 6000.7 6393.4 6815.7 6824.8 6844.5 5071.9 5552.9 5982.8 6178.5 6640.6 5352.8 5472.2 5699.9 6396.3 6596.3 5385.0 6098.2 6270.4 6418.8 6496.9 5042.5 6334.9 6590.9 6596.1 6638.8 5000.3 6230.8 6405.3 6444.9 6466.3 16:40:24 INFO - PROCESS | 1664 | 16:40:24 INFO - PROCESS | 1664 | [#5] yui.html Cycles:180 Average:17451.21 Median:29673.69 stddev:23822.23 (80.3%) stddev-sans-first:23855.81 16:40:24 INFO - PROCESS | 1664 | Values: 691.4 716.9 736.8 747.5 759.9 14254.3 14397.2 14844.7 14898.6 14935.0 11489.9 11501.5 13914.0 13929.8 13959.0 727.1 785.2 908.4 918.8 927.6 855.4 938.9 939.9 940.5 941.7 977.0 983.9 985.4 985.8 988.3 940.1 942.4 944.8 958.9 984.5 720.3 722.7 724.5 724.5 725.5 12551.6 15181.5 15300.6 15326.5 15338.0 12471.9 12569.4 12595.6 12610.2 12834.8 11090.4 11110.9 11112.4 11268.7 11480.0 4023.6 4056.6 4083.8 4104.4 4222.0 71179.3 73771.6 74121.6 74205.6 80236.0 9689.0 9740.0 9796.0 9883.3 9923.8 22029.8 22451.8 22632.4 22677.0 22696.0 7675.7 7740.3 7811.3 7953.0 7983.4 4101.5 4141.5 4159.8 4166.3 4171.5 3613.1 3644.8 3706.2 3706.8 3727.5 561.7 658.3 665.0 676.3 678.7 548.5 572.8 589.5 597.4 608.2 7867.8 8022.8 8142.8 8170.3 8242.3 508.7 562.8 570.1 582.4 596.7 3727.8 3840.8 3879.3 3881.4 3911.4 80022.0 83547.7 83832.0 83936.0 86074.0 70829.6 72258.0 76203.0 78199.6 78446.2 68641.0 69508.7 70108.3 70757.6 71593.6 7392.0 8291.6 8461.3 8464.2 8553.7 35063.6 38910.6 43836.8 44060.9 44324.3 58765.0 61945.8 62201.7 62854.7 63471.0 11254.0 11391.7 11477.7 11541.7 11971.1 11665.4 11845.8 11900.8 11954.7 11981.2 11377.9 11400.0 11470.9 11501.9 11612.1 11439.0 11512.3 11737.7 11753.1 11764.9 11393.7 11490.1 11517.9 11546.4 11687.5 7943.5 8159.2 8197.5 8381.8 9039.2 9180.2 10057.8 10060.5 10074.0 10077.7 16:40:24 INFO - PROCESS | 1664 | -------- Summary: end -------- 16:40:24 INFO - PROCESS | 1664 | 16:40:24 INFO - TEST-INFO | 1664: exit 0 16:40:24 INFO - TEST-OK | dromaeo_css | took 1111782ms 16:40:24 INFO - TEST-START | kraken 16:40:24 INFO - operating with platform_type : mac_ 16:40:24 INFO - Initialising browser for kraken test... 16:40:24 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/tmpV6dE1u/profile http://localhost:49275/getInfo.html) 16:40:27 INFO - PROCESS | 1676 | __metrics Screen width/height:1600/1200 16:40:27 INFO - PROCESS | 1676 | colorDepth:24 16:40:27 INFO - PROCESS | 1676 | Browser inner width/height: 1024/689 16:40:27 INFO - PROCESS | 1676 | __metrics 16:40:27 ERROR - PROCESS | 1676 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 16:40:28 INFO - TEST-INFO | 1676: exit 0 16:40:28 INFO - Browser initialized. 16:40:28 INFO - Running cycle 1/1 for kraken test... 16:40:28 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/tmpV6dE1u/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:40:40 INFO - PROCESS | 1679 | RSS: Main: 247959552 16:40:40 INFO - PROCESS | 1679 | 16:40:43 INFO - PROCESS | 1679 | 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) 16:40:44 INFO - PROCESS | 1679 | RSS: Main: 260239360 16:40:44 INFO - PROCESS | 1679 | 16:40:46 INFO - PROCESS | 1679 | 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) 16:40:47 INFO - PROCESS | 1679 | RSS: Main: 269307904 16:40:47 INFO - PROCESS | 1679 | 16:40:51 INFO - PROCESS | 1679 | 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) 16:40:51 INFO - PROCESS | 1679 | RSS: Main: 272248832 16:40:51 INFO - PROCESS | 1679 | 16:40:53 INFO - PROCESS | 1679 | 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) 16:40:54 INFO - PROCESS | 1679 | RSS: Main: 274255872 16:40:54 INFO - PROCESS | 1679 | 16:40:56 INFO - PROCESS | 1679 | 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) 16:40:56 INFO - PROCESS | 1679 | RSS: Main: 274665472 16:40:56 INFO - PROCESS | 1679 | 16:40:59 INFO - PROCESS | 1679 | 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) 16:41:00 INFO - PROCESS | 1679 | RSS: Main: 274907136 16:41:00 INFO - PROCESS | 1679 | 16:41:03 INFO - PROCESS | 1679 | 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) 16:41:04 INFO - PROCESS | 1679 | RSS: Main: 275075072 16:41:04 INFO - PROCESS | 1679 | 16:41:07 INFO - PROCESS | 1679 | 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) 16:41:07 INFO - PROCESS | 1679 | RSS: Main: 275058688 16:41:07 INFO - PROCESS | 1679 | 16:41:09 INFO - PROCESS | 1679 | 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) 16:41:10 INFO - PROCESS | 1679 | RSS: Main: 275116032 16:41:10 INFO - PROCESS | 1679 | 16:41:12 INFO - PROCESS | 1679 | 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) 16:41:12 INFO - PROCESS | 1679 | RSS: Main: 275095552 16:41:12 INFO - PROCESS | 1679 | 16:41:14 INFO - PROCESS | 1679 | 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) 16:41:15 INFO - PROCESS | 1679 | RSS: Main: 275132416 16:41:15 INFO - PROCESS | 1679 | 16:41:18 INFO - PROCESS | 1679 | 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) 16:41:19 INFO - PROCESS | 1679 | RSS: Main: 275234816 16:41:19 INFO - PROCESS | 1679 | 16:41:22 INFO - PROCESS | 1679 | 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) 16:41:22 INFO - PROCESS | 1679 | RSS: Main: 275181568 16:41:22 INFO - PROCESS | 1679 | 16:41:24 INFO - PROCESS | 1679 | 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) 16:41:24 INFO - PROCESS | 1679 | RSS: Main: 276705280 16:41:24 INFO - PROCESS | 1679 | 16:41:24 INFO - PROCESS | 1679 | __start_tp_report 16:41:24 INFO - PROCESS | 1679 | _x_x_mozilla_page_load 16:41:24 INFO - PROCESS | 1679 | _x_x_mozilla_page_load_details 16:41:24 INFO - PROCESS | 1679 | |i|pagename|runs| 16:41:24 INFO - PROCESS | 1679 | |0;ai-astar;112;112;143;169;119;131;116;117;119;136 16:41:24 INFO - PROCESS | 1679 | |1;audio-beat-detection;154;123;142;175;125;130;169;156;206;123 16:41:24 INFO - PROCESS | 1679 | |2;audio-dft;197;222;257;254;263;277;196;201;202;197 16:41:24 INFO - PROCESS | 1679 | |3;audio-fft;68;81;76;136;64;81;94;71;74;56 16:41:24 INFO - PROCESS | 1679 | |4;audio-oscillator;82;83;126;79;82;83;121;83;117;123 16:41:24 INFO - PROCESS | 1679 | |5;imaging-gaussian-blur;91;88;103;127;132;87;91;89;90;87 16:41:24 INFO - PROCESS | 1679 | |6;imaging-darkroom;148;96;138;106;144;111;91;88;102;147 16:41:24 INFO - PROCESS | 1679 | |7;imaging-desaturate;114;147;91;90;90;92;97;90;90;91 16:41:24 INFO - PROCESS | 1679 | |8;json-parse-financial;47;55;93;49;76;75;91;102;96;95 16:41:24 INFO - PROCESS | 1679 | |9;json-stringify-tinderbox;65;68;86;101;109;106;96;66;66;68 16:41:24 INFO - PROCESS | 1679 | |10;stanford-crypto-aes;82;85;85;82;85;81;86;85;87;84 16:41:24 INFO - PROCESS | 1679 | |11;stanford-crypto-ccm;139;144;140;139;164;160;160;143;175;171 16:41:24 INFO - PROCESS | 1679 | |12;stanford-crypto-pbkdf2;189;176;171;172;234;220;169;169;173;177 16:41:24 INFO - PROCESS | 1679 | |13;stanford-crypto-sha256-iterative;71;70;69;72;71;71;69;74;62;81 16:41:24 INFO - PROCESS | 1679 | __end_tp_report 16:41:24 INFO - PROCESS | 1679 | __start_cc_report 16:41:24 INFO - PROCESS | 1679 | _x_x_mozilla_cycle_collect,1034 16:41:24 INFO - PROCESS | 1679 | __end_cc_report 16:41:24 INFO - PROCESS | 1679 | __startTimestamp1461282084878__endTimestamp 16:41:24 INFO - PROCESS | 1679 | 16:41:24 INFO - PROCESS | 1679 | ------- Summary: start ------- 16:41:24 INFO - PROCESS | 1679 | Number of tests: 14 16:41:24 INFO - PROCESS | 1679 | 16:41:24 INFO - PROCESS | 1679 | [#0] ai-astar Cycles:10 Average:127.40 Median:125.00 stddev:18.00 (14.4%) stddev-sans-first:18.20 16:41:24 INFO - PROCESS | 1679 | Values: 112.0 112.0 143.0 169.0 119.0 131.0 116.0 117.0 119.0 136.0 16:41:24 INFO - PROCESS | 1679 | 16:41:24 INFO - PROCESS | 1679 | [#1] audio-beat-detection Cycles:10 Average:150.30 Median:155.00 stddev:27.33 (17.6%) stddev-sans-first:28.95 16:41:24 INFO - PROCESS | 1679 | Values: 154.0 123.0 142.0 175.0 125.0 130.0 169.0 156.0 206.0 123.0 16:41:24 INFO - PROCESS | 1679 | 16:41:24 INFO - PROCESS | 1679 | [#2] audio-dft Cycles:10 Average:226.60 Median:238.00 stddev:32.51 (13.7%) stddev-sans-first:32.67 16:41:24 INFO - PROCESS | 1679 | Values: 197.0 222.0 257.0 254.0 263.0 277.0 196.0 201.0 202.0 197.0 16:41:24 INFO - PROCESS | 1679 | 16:41:24 INFO - PROCESS | 1679 | [#3] audio-fft Cycles:10 Average:80.10 Median:75.00 stddev:22.22 (29.6%) stddev-sans-first:23.13 16:41:24 INFO - PROCESS | 1679 | Values: 68.0 81.0 76.0 136.0 64.0 81.0 94.0 71.0 74.0 56.0 16:41:24 INFO - PROCESS | 1679 | 16:41:24 INFO - PROCESS | 1679 | [#4] audio-oscillator Cycles:10 Average:97.90 Median:82.00 stddev:20.67 (25.2%) stddev-sans-first:21.11 16:41:24 INFO - PROCESS | 1679 | Values: 82.0 83.0 126.0 79.0 82.0 83.0 121.0 83.0 117.0 123.0 16:41:24 INFO - PROCESS | 1679 | 16:41:24 INFO - PROCESS | 1679 | [#5] imaging-gaussian-blur Cycles:10 Average:98.50 Median:88.50 stddev:17.01 (19.2%) stddev-sans-first:17.83 16:41:24 INFO - PROCESS | 1679 | Values: 91.0 88.0 103.0 127.0 132.0 87.0 91.0 89.0 90.0 87.0 16:41:24 INFO - PROCESS | 1679 | 16:41:24 INFO - PROCESS | 1679 | [#6] imaging-darkroom Cycles:10 Average:117.10 Median:147.50 stddev:24.43 (16.6%) stddev-sans-first:23.21 16:41:24 INFO - PROCESS | 1679 | Values: 148.0 96.0 138.0 106.0 144.0 111.0 91.0 88.0 102.0 147.0 16:41:24 INFO - PROCESS | 1679 | 16:41:24 INFO - PROCESS | 1679 | [#7] imaging-desaturate Cycles:10 Average:99.20 Median:90.50 stddev:18.36 (20.3%) stddev-sans-first:18.68 16:41:24 INFO - PROCESS | 1679 | Values: 114.0 147.0 91.0 90.0 90.0 92.0 97.0 90.0 90.0 91.0 16:41:24 INFO - PROCESS | 1679 | 16:41:24 INFO - PROCESS | 1679 | [#8] json-parse-financial Cycles:10 Average:77.90 Median:83.50 stddev:20.89 (25.0%) stddev-sans-first:18.93 16:41:24 INFO - PROCESS | 1679 | Values: 47.0 55.0 93.0 49.0 76.0 75.0 91.0 102.0 96.0 95.0 16:41:24 INFO - PROCESS | 1679 | 16:41:24 INFO - PROCESS | 1679 | [#9] json-stringify-tinderbox Cycles:10 Average:83.10 Median:67.00 stddev:18.44 (27.5%) stddev-sans-first:18.35 16:41:24 INFO - PROCESS | 1679 | Values: 65.0 68.0 86.0 101.0 109.0 106.0 96.0 66.0 66.0 68.0 16:41:24 INFO - PROCESS | 1679 | 16:41:24 INFO - PROCESS | 1679 | [#10] stanford-crypto-aes Cycles:10 Average:84.20 Median:85.00 stddev:1.93 (2.3%) stddev-sans-first:1.88 16:41:24 INFO - PROCESS | 1679 | Values: 82.0 85.0 85.0 82.0 85.0 81.0 86.0 85.0 87.0 84.0 16:41:24 INFO - PROCESS | 1679 | 16:41:24 INFO - PROCESS | 1679 | [#11] stanford-crypto-ccm Cycles:10 Average:153.50 Median:160.00 stddev:14.01 (8.8%) stddev-sans-first:13.84 16:41:24 INFO - PROCESS | 1679 | Values: 139.0 144.0 140.0 139.0 164.0 160.0 160.0 143.0 175.0 171.0 16:41:24 INFO - PROCESS | 1679 | 16:41:24 INFO - PROCESS | 1679 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.00 Median:176.50 stddev:23.11 (13.1%) stddev-sans-first:24.47 16:41:24 INFO - PROCESS | 1679 | Values: 189.0 176.0 171.0 172.0 234.0 220.0 169.0 169.0 173.0 177.0 16:41:24 INFO - PROCESS | 1679 | 16:41:24 INFO - PROCESS | 1679 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.00 Median:71.00 stddev:4.71 (6.6%) stddev-sans-first:5.00 16:41:24 INFO - PROCESS | 1679 | Values: 71.0 70.0 69.0 72.0 71.0 71.0 69.0 74.0 62.0 81.0 16:41:24 INFO - PROCESS | 1679 | -------- Summary: end -------- 16:41:24 INFO - PROCESS | 1679 | 16:41:25 INFO - TEST-INFO | 1679: exit 0 16:41:25 INFO - TEST-OK | kraken | took 60762ms 16:41:25 INFO - SUITE-END | took 1172s 16:41:26 INFO - Completed test suite (00:19:32) 16:41:26 INFO - javascript benchmark 16:41:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3416.658334166583, 3421.98289008555, 3449.758516903816, 3471.0, 3510.947335789962, 5675.318961724589, 5701.6864072476, 5731.0, 5736.569757268206, 5751.194832723417, 4141.896452588687, 4405.955940440603, 4719.787609557569, 5241.5806735461165, 5381.919271210935, 5147.871303217412, 5196.58427325815, 5213.687178769286, 5269.341332333458, 5344.5724342052545, 430.6907349339731, 5191.325127733408, 5250.8687282817855, 5258.395284542269, 5322.121849894763, 5192.818251361203, 5204.34945631796, 5222.764975576109, 5227.895442091137, 5294.814681486168, 5115.565176959925, 5315.989961907225, 5323.760430780624, 5331.653442526263, 5378.838634840961, 4516.5935065843905, 5111.514406131449, 5139.820106296299, 5170.0, 5235.502627250406, 5521.309836270466, 5561.77752889884, 5581.944180558183, 5602.1596760485845, 5676.120201368794, 6113.633182009094, 6120.9347721082095, 6184.381561843825, 6222.12890195373, 6296.716647750863, 5242.8689282767855, 6246.156768836195, 6261.937380626181, 6288.056791481269, 6320.62076275425, 5868.149118377854, 5882.411758824126, 5889.558283128783, 5892.499137573269, 5896.086106653475, 6198.411150940744, 6257.843553911189, 6322.209723784527, 6330.955392360301, 6331.0, 6175.042868355412, 6188.381161883865, 6200.193974783249, 6206.565540412128, 6229.813105606839, 6260.625065432878, 6271.247450305992, 6305.779297724557, 6313.747450101944, 6338.049292606146, 6139.293981192131, 6147.047207682816, 6233.875322493615, 6242.531810114168, 6260.749570017239, 6009.2188015557695, 6158.261008678987, 6261.029540421242, 6289.371062893765, 6296.087067375205, 4440.7335559866515, 4886.560209581155, 4961.181405068195, 4962.478939711351, 4967.180415231519, 4351.173277077409, 4451.399061126771, 4469.865904022885, 4488.955110448919, 4500.797464114123, 5753.453421924911, 5827.446392592782, 5863.7947671832135, 5884.558658100659, 5945.078512830518, 6223.564350495512, 6228.532860035515, 6235.407636274637, 6244.65654389013, 6258.155149054819, 5797.304323481125, 5823.0, 5842.795502157404, 5843.795467158629, 5877.059670452707, 5336.03951288764, 5406.783728650888, 5422.91865622016, 5521.089020311603, 5531.0, 6352.110704501373, 6356.809295721136, 6358.809235722936, 6379.106925030499, 6385.840353991094, 6163.630182189083, 6241.21984751906, 6259.968700156653, 6268.059791031382, 6268.968655156695, 6229.470495007794, 6307.779227727007, 6339.651319177305, 6420.229572451336, 6438.3883531064475, 6246.5002799775, 6259.655718935503, 6283.02613094971, 6296.5592408530965, 6297.307296197506, 6154.446099851035, 6251.812445626639, 6271.3728627136925, 6274.749010039547, 6287.497000239879, 5433.9184912225555, 5543.3902270751, 6190.071489276645, 6212.751489940351, 6218.098375735583, 5657.462541058593, 5914.996278776959, 6138.754449821957, 6149.108379285068, 6173.135760993554, 5771.884562308646, 6010.068439391901, 6028.0, 6075.058365953284, 6088.452039316483, 6040.456358927542, 6108.57239993214, 6112.533877023262, 6130.249081909528, 6215.502759779117, 6108.75564977396, 6110.572259941761, 6116.5412594056515, 6142.508599312134, 6147.170132032118], "value": 5656.468856847283, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [510.9872253193672, 528.5718567959952, 547.843864498618, 586.0915580849681, 595.6902410746412, 37671.623283767156, 38403.23193536128, 38420.61579384205, 38526.61473385265, 38571.99999999996, 39622.0, 40257.79871100648, 40287.39568906469, 40367.0, 40539.59460405395, 9250.398724082914, 9444.622215111387, 9510.857337139949, 9516.857247141264, 9544.141027307542, 9375.203107735817, 9585.616575336979, 9617.374870633386, 9625.71122866315, 9628.037196280386, 9587.280953928483, 9624.0, 9637.855432168522, 9649.807003859918, 9654.179394751454, 9562.713118606418, 9621.470819104947, 9636.759081022961, 9642.42145471274, 9645.855312170323, 9423.199028082552, 9547.570359333846, 9548.761280967961, 9564.66523671668, 9566.13904748576, 38485.65300214479, 38856.80571597124, 38964.415533767016, 39052.0, 39239.21521569581, 2724.359775452767, 3032.9545056824163, 3043.6195475565555, 3168.493041113411, 3356.077078803324, 28122.296942576395, 28307.726152323194, 28639.57040644392, 29045.854770726222, 29853.850730746424, 2686.8790904409348, 2740.931476713078, 2749.1889892481768, 2840.318323602341, 2843.536065085818, 485490.0, 487914.0, 488568.0, 497255.0, 498838.0, 70104.0, 70175.2982470179, 71008.57982840315, 71210.0, 71278.64360678164, 74582.0, 74613.62693186608, 76343.47313053819, 76869.0, 77091.61454192693, 17223.22495487706, 17505.862118962228, 17531.73702394465, 17546.473605791845, 17584.296628134987, 12837.165584237182, 13089.476420943247, 13142.60572182836, 13253.734925301633, 13273.800892986614, 20575.691364629543, 20853.582928341653, 20914.581708365746, 20950.685739713917, 21478.0, 7266.854662906818, 8801.515916624647, 8823.955880220687, 8828.85224920769, 8838.381313308008, 865.1175800683268, 916.922061624756, 946.3895787217207, 993.7664648807524, 1153.955670118544, 29386.55920161199, 29489.557656635166, 29736.0, 30197.0, 30283.0, 1078.245228340165, 1102.9007389334838, 1137.6201623748666, 1164.8602167739757, 1174.7121955120879, 15101.0, 15195.544133676007, 15207.923960380127, 15505.518057963447, 15636.921815391077, 451049.0, 490456.09543904103, 575712.1214393901, 586498.0, 590310.0, 58464.83070338569, 58604.70697646485, 59568.0, 59574.702126489094, 59795.40204597898, 1479.4156308258393, 1481.6147801571453, 1490.3219035338896, 1494.2304713072776, 1512.0927443534013, 28628.71371286344, 29258.561121583618, 29407.26481837971, 29680.406391872475, 29934.70065299319, 51126.97746044984, 51159.74420127876, 51605.22592161046, 51692.0, 51875.481245188574, 543143.0, 558790.0, 561688.0, 563990.9804960766, 567584.0, 27324.0, 27761.86119069392, 27846.582301265902, 27854.88580456759, 27872.860635696692, 45262.0, 45371.09257814891, 45564.0, 45829.31256031096, 46627.53372466368, 37728.81135594304, 37800.0, 37904.0, 38655.6134438652, 38896.02759931024, 33197.50203746898, 36431.27137457183, 36802.0, 37521.81239093787, 38347.42478862875, 36896.81551592332, 37137.0, 37838.0, 37978.0, 38137.23725525529, 32140.357192855543, 33052.33895322032, 33160.668393315755, 33195.83402082974, 33335.666643334225, 23738.762612373655, 23801.571905685716, 24640.630390543796, 24684.25947221586, 25095.823486187575], "value": 20058.51089983726, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.536863655656, 395.9604039596041, 396.26492855752576, 398.4461598378254, 406.37012630422856, 17604.20781064852, 18467.815321846778, 20885.686714699255, 21427.464313392175, 21430.464238394048, 20073.698894516594, 20744.48138796531, 21124.683129753026, 21180.046897889595, 21373.786262137375, 5795.0, 6014.909776353358, 6583.275839657634, 7075.0, 7096.645167741593, 6780.660966951657, 6969.6166710830885, 7051.012858199856, 7159.713611455535, 7185.676644550982, 7149.070620819312, 7160.892586611205, 7167.139943206823, 7176.6052867092285, 7181.964090179542, 5511.338639363282, 5754.3094828620415, 5764.654120752768, 5897.0, 5907.497862681677, 5559.63862348946, 5645.350784659735, 5865.208196893407, 5868.178455016301, 5877.177195192677, 19218.174273444, 24055.0, 24367.756322436726, 25550.105746298977, 25833.74166258351, 1889.5559543507266, 2117.163720330461, 2278.100150440583, 2292.965605515902, 2436.537057958982, 10042.497875106215, 10199.0820826126, 11246.831297530543, 13541.796873046813, 14871.958962872603, 1524.3902439024369, 1716.4678949525562, 1735.9218835152449, 1745.9214335354814, 1765.1791916758768, 118557.8144218564, 121395.0, 122074.38962805129, 124016.0, 126164.0, 9621.951890240503, 10275.177985761122, 11686.47410866513, 11792.23350482216, 11868.347240901661, 15051.0, 15056.924715376572, 15076.623084422978, 15099.773503397459, 15119.697606048037, 7177.497575169688, 8600.483970961637, 8760.4305720128, 8773.298136149093, 8886.86669699955, 8485.787855303666, 8562.315014798802, 8602.053774084845, 8671.089535598798, 8722.127787221227, 4209.894752631209, 4563.155816174019, 4613.63090952723, 4618.399608050931, 4645.605123564535, 1767.2665843674731, 1839.5677015901254, 1907.761529808774, 1968.8228059474716, 1975.387629834756, 870.4385671242013, 908.1644886704248, 968.1625394034251, 1003.172382784206, 1003.372891942536, 19163.137658805386, 19969.900150499154, 20059.799402005792, 20129.993500324927, 20461.488462788548, 962.932594718377, 981.0385821894583, 991.8512223166439, 1007.219404961161, 1014.3508154780947, 10233.692989210187, 10293.485325733684, 10450.68647940563, 10464.73838154037, 10517.52671129801, 561220.0, 596501.0, 608704.0, 612316.0, 613170.0, 126459.7354026448, 126549.0, 127465.36267318789, 128913.71086289018, 131483.0, 870.3602851903719, 999.2655398282257, 1011.7420057885192, 1036.5646428500047, 1038.9272750907514, 17230.65538689194, 17298.567535811704, 17532.912335438243, 17613.0, 17771.289148433916, 32347.51478727868, 44683.106337872414, 45598.772006139756, 45752.313715294964, 46616.06767864556, 52438.21342679939, 67466.6506669854, 67739.32260677527, 68050.21573166337, 69071.0, 7807.102183248772, 7929.0, 8017.719379821679, 8065.951426314541, 8362.958185209034, 12261.203021803554, 12587.622371328875, 12597.43311550964, 12807.487700491876, 12897.742045158857, 10823.56705731762, 10888.346699197757, 10918.727031824268, 10928.344299342065, 10986.176036797113, 10688.0, 10713.14294856394, 10957.178211634318, 11057.170712196457, 11173.608923687632, 10975.0, 11068.667939961813, 11228.775424491629, 11350.886491135314, 11510.136739744385, 8036.0, 8297.547929112501, 8302.626381812954, 8318.750437486886, 8491.8726219108, 9903.702888913343, 10997.285176463503, 11188.608398706006, 11240.4379781009, 11247.831282530933], "value": 9582.331881891496, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1346.468145082692, 1599.4002249156567, 1749.5013921032507, 1893.7632795900504, 1917.4439412570355, 7811.687532498708, 7953.164917683646, 8549.358798090145, 8825.691100811466, 8840.60217290222, 7136.536125151874, 8574.18545238203, 8711.30309575234, 8717.0, 8729.476231426119, 2345.835791494596, 2432.8905199266032, 2445.2786428003783, 2464.6549483072386, 2469.8271121021535, 2228.7882651148116, 2406.3142004528713, 2425.1754403502805, 2433.2943446400523, 2433.5011322678806, 2433.586290330648, 2451.779339859412, 2462.273629279367, 2468.1854987853967, 2478.6405971134177, 2429.829911906167, 2436.7319594844553, 2443.0960544598524, 2451.9754802451926, 2460.5817011108156, 2416.0215112879314, 2417.5406672732124, 2433.3794882305074, 2442.951140977188, 2443.8044956403623, 8777.29781617469, 8856.468611883243, 8858.247049000842, 8868.113188681145, 8899.688510902151, 5208.270842082112, 5417.83746487606, 5451.972740136313, 5452.345718513764, 5453.23654688344, 3516.3670539302916, 3541.610422853484, 3571.357155711971, 3623.5832879218965, 3625.166211771307, 1026.1021606094666, 1039.6101461951769, 1040.0847254416065, 1049.9160067194607, 1054.204075922681, 9661.0, 11470.254433461881, 11530.308181509052, 11606.535738570446, 11631.418429078512, 4628.74541900192, 4700.0, 4842.15262329091, 4847.3698419205275, 4903.509649035068, 8688.304935605136, 9800.754981125387, 9905.365614673497, 9944.80110397788, 9944.950275248577, 5380.0, 5393.865153371119, 5402.35228255378, 5402.702851343135, 5417.945820541823, 4386.978065109654, 4774.809007639656, 4861.197902346144, 4876.024795041006, 4910.656254062253, 3974.82113304902, 4002.539707933625, 4064.288749468831, 4087.4073259377224, 4141.855035073758, 1158.8783177766213, 1351.8310211223597, 1378.3383975691725, 1379.8551152129082, 1386.3553447646893, 896.2874514760781, 918.9310801689767, 919.8114386550741, 941.3222479814523, 957.6169532187041, 5730.74211660468, 5878.147668588031, 5896.616719913192, 5914.615549989237, 5965.373635768268, 434.8673654535334, 442.1643094551301, 450.27730492559624, 455.2192989023774, 468.08255818595256, 3272.7709060365546, 3298.3238436120537, 3879.689624830007, 3956.4263181838473, 3966.841326346914, 10437.634682786065, 10722.303050301705, 10769.138468922467, 10776.245662803685, 10932.890671093346, 10550.0, 10723.410212438392, 10784.083352914933, 10842.837357439645, 10916.235863489639, 6624.8012559623285, 7621.504602200839, 8042.0, 8072.959635201903, 8124.431289809657, 5766.163906233489, 5846.795362162304, 5945.64326140433, 6031.909521357271, 6070.0, 9656.130948214695, 9800.068993445611, 9824.901750982399, 9848.212143028397, 9926.652567160114, 8748.912510875065, 9933.552990115317, 9971.601135954481, 10012.0, 10101.040401161878, 6859.142607174103, 7269.890951635624, 7467.0, 7525.661345239368, 7636.350910172698, 8233.876491852507, 8253.0, 8314.293285070837, 8326.417150799385, 8354.498730076215, 7260.382867456326, 7392.593407362647, 7401.33387995083, 7500.474966752275, 7555.168931417649, 7028.226895041644, 7062.435005199674, 7113.608751518716, 7130.108736407949, 7151.499395042297, 6887.449004079762, 6903.447724182154, 6930.826729331606, 6969.512134150561, 7008.894866577108, 4786.880327991828, 4844.636652251166, 4880.341153944172, 4952.8018879245865, 5076.36545431821, 4908.509149085063, 4912.410510738734, 4932.383452068491, 5005.949940500694, 5095.827959569394], "value": 4497.343280801129, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [315.16324159356907, 316.6074068155488, 323.574499533114, 325.85336598530665, 328.8963976347452, 6174.444300012998, 6181.350958149392, 6252.906206406908, 6309.1482649842255, 6312.6212427254395, 5337.733113344337, 5814.186013958049, 6137.38626137386, 6183.0, 6210.2547694276755, 3834.4662135146027, 4067.430559721641, 4322.200392927319, 4347.93478097828, 4447.822087116511, 3558.252766918937, 4135.483064616923, 4534.523874993111, 4584.404027476424, 4649.721016738989, 4581.633469322463, 4613.97784451185, 4621.838235661752, 4628.282616194495, 4646.070785842828, 4486.730796152225, 4602.884927876813, 4613.0, 4620.330052142454, 4657.673962822604, 3992.3612222044626, 4006.3389540725743, 4010.278149933011, 4029.214303210875, 4034.798260087013, 5657.20799088128, 5944.840756052571, 5997.352052159611, 6001.729922153513, 6002.549808764316, 4519.141363140992, 4868.123737727207, 5387.4073851876265, 5400.891982160374, 5426.158945363475, 5605.579581531401, 6180.907286390708, 6475.93524064758, 6521.902171467432, 6585.769498067544, 4126.607972242632, 4183.912182832485, 4302.182585308813, 4429.534898835608, 4432.645388368923, 6055.394460553954, 6724.596524208514, 6896.689648965808, 6906.551074180162, 6921.273266307016, 4099.1801639672185, 4244.957550424456, 4473.329000649928, 4508.18852606534, 4535.319702044654, 3686.907827304285, 3876.980615096963, 4540.72755634663, 4608.239640459286, 4620.306953956933, 4229.471316085489, 5172.19830926207, 5367.463253674679, 5399.568034557226, 5439.728013599304, 5733.971330143323, 6222.3466536013975, 6393.680315984182, 6433.3594933291715, 6606.405423511907, 1348.7774517204552, 1513.448715067065, 1582.8812839037118, 1604.3502381535498, 1607.6141725985913, 977.953589659058, 1030.845373194012, 1039.932404393712, 1050.1965996013057, 1055.171690223171, 5207.6875387476875, 5440.6735595864375, 5616.747246373923, 6043.0, 6096.268447786294, 5637.8160586277345, 5805.274340707411, 6745.2242992056545, 6751.831204219934, 6784.728610855609, 4872.220444728827, 5271.762770675329, 5702.572307076985, 6031.155638210654, 6083.513318934474, 2631.973680263211, 3294.258791771832, 3335.93328133436, 3556.4309710446723, 3573.6783689468075, 6158.568900177034, 6754.290799465985, 6758.087658166183, 7062.081929349151, 7070.575765454089, 5713.514351280104, 6638.369354911276, 6882.621455819878, 6943.583384996917, 6962.126511442011, 6558.983357579583, 6769.93230067693, 6996.545224560489, 7001.474889383215, 7085.610291434021, 5876.41235876409, 6705.631190284581, 6741.1236539249585, 6898.068760717239, 6923.134608173978, 6592.934070659424, 6697.430718388991, 6717.126773519607, 6736.393724564813, 6749.966250168718, 6378.617282963037, 6585.46459613062, 7201.279872012757, 7202.747903823341, 7252.419806415369, 4364.388985541963, 4375.540568240416, 4391.8682439526865, 4399.67002474822, 4409.80155892992, 6000.729967151576, 6393.424591786761, 6815.659217039029, 6824.761133360308, 6844.5208835381045, 5071.906137256197, 5552.861178470571, 5982.790602328898, 6178.474829639351, 6640.646473530836, 5352.798053527962, 5472.233887255867, 5699.857503562278, 6396.264429590657, 6596.30738772422, 5384.9730751346, 6098.237720284965, 6270.435660790551, 6418.84460797061, 6496.906948622526, 5042.4705405931845, 6334.90497642545, 6590.90113648286, 6596.142501474777, 6638.767643132467, 5000.349954505891, 6230.847293250763, 6405.306454754699, 6444.9355506444335, 6466.321036291121], "value": 4685.438244409097, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [691.3535843985874, 716.9283071692835, 736.8306378932979, 747.4879707400431, 759.9278068583484, 14254.287285635703, 14397.208153551564, 14844.703105937875, 14898.627534311647, 14935.0, 11489.885101148986, 11501.539938402475, 13914.0, 13929.791053134186, 13959.0, 727.08387431836, 785.1755656560617, 908.4276905549494, 918.8024574716435, 927.6104036304769, 855.4054931822386, 938.8591711243334, 939.9436033837958, 940.501534186882, 941.7174847545743, 976.9951150244239, 983.8868530119021, 985.3595163143942, 985.8373368394207, 988.298308201178, 940.116290686759, 942.3874481586989, 944.7968686732316, 958.9184919281904, 984.5421878826344, 720.2617317249808, 722.743426083738, 724.5000949344987, 724.5435375713267, 725.4595326481805, 12551.623451296384, 15181.468648597245, 15300.617484562976, 15326.463573774976, 15338.0, 12471.875281247252, 12569.434375453127, 12595.622131336075, 12610.243385396905, 12834.844863962287, 11090.390028548427, 11110.94572535716, 11112.388818614972, 11268.661940141808, 11480.0, 4023.6177563131455, 4056.6146216109423, 4083.8162282697053, 4104.445899803518, 4221.957780422187, 71179.28820711779, 73771.63114184448, 74121.6293918527, 74205.62897185479, 80236.0, 9689.031096890254, 9740.0, 9795.951020244853, 9883.258755593359, 9923.751906202402, 22029.779702202773, 22451.775482245295, 22632.434189145075, 22677.0, 22696.0, 7675.692972281158, 7740.303372696371, 7811.336036436966, 7953.005874265717, 7983.441159118806, 4101.5488296287385, 4141.544430112685, 4159.792010399528, 4166.250074986531, 4171.478565179353, 3613.1328481164333, 3644.781313121221, 3706.2031663192006, 3706.7590606610484, 3727.5154229950476, 561.6994907724429, 658.3054877104701, 664.9900251496232, 676.3202981004158, 678.6606696651675, 548.4707257496456, 572.7714053355529, 589.4812564942823, 597.4085655201319, 608.1819952164332, 7867.803304917308, 8022.799430014296, 8142.796430089295, 8170.264676179054, 8242.29940455068, 508.7354575620657, 562.8114581615205, 570.1419363857368, 582.3855832097178, 596.6658671144173, 3727.794971276606, 3840.8271627776817, 3879.262940041383, 3881.3789793633446, 3911.3741801311653, 80022.0, 83547.7467837983, 83832.0, 83936.0, 86074.0, 70829.64585177145, 72258.0, 76203.0, 78199.60900195462, 78446.21553784503, 68640.999999999, 69508.6524567384, 70108.2989170112, 70757.64621176862, 71593.64203178951, 7392.002079719169, 8291.626876790438, 8461.280791132576, 8464.238218560113, 8553.700620478254, 35063.64936350604, 38910.6108938907, 43836.780816095714, 44060.89847753832, 44324.33513497365, 58765.0, 61945.761084778955, 62201.68899155475, 62854.68572657107, 63471.0, 11254.0, 11391.715207119556, 11477.655670329903, 11541.711457213636, 11971.10216733731, 11665.35840528779, 11845.822312665145, 11900.821487677866, 11954.70113247141, 11981.161318707607, 11377.943110284396, 11400.0, 11470.942645287054, 11501.884981150417, 11612.129090318429, 11439.0, 11512.251703639236, 11737.706557336134, 11753.059755219732, 11764.882351176379, 11393.715157121138, 11490.138239631893, 11517.884821151682, 11546.42267886619, 11687.474063666985, 7943.523388596703, 8159.224873636995, 8197.508149511048, 8381.790455238668, 9039.231665308522, 9180.219681326895, 10057.849132263169, 10060.547275372772, 10074.042965918226, 10077.69766906994], "value": 6869.522939459611, "unit": "score"}], "name": "dromaeo_css", "value": 7347.929050076802, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 112.0, 143.0, 169.0, 119.0, 131.0, 116.0, 117.0, 119.0, 136.0], "value": 127.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 123.0, 142.0, 175.0, 125.0, 130.0, 169.0, 156.0, 206.0, 123.0], "value": 150.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 222.0, 257.0, 254.0, 263.0, 277.0, 196.0, 201.0, 202.0, 197.0], "value": 226.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 81.0, 76.0, 136.0, 64.0, 81.0, 94.0, 71.0, 74.0, 56.0], "value": 80.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 126.0, 79.0, 82.0, 83.0, 121.0, 83.0, 117.0, 123.0], "value": 97.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 88.0, 103.0, 127.0, 132.0, 87.0, 91.0, 89.0, 90.0, 87.0], "value": 98.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 96.0, 138.0, 106.0, 144.0, 111.0, 91.0, 88.0, 102.0, 147.0], "value": 117.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 147.0, 91.0, 90.0, 90.0, 92.0, 97.0, 90.0, 90.0, 91.0], "value": 99.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [47.0, 55.0, 93.0, 49.0, 76.0, 75.0, 91.0, 102.0, 96.0, 95.0], "value": 77.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 68.0, 86.0, 101.0, 109.0, 106.0, 96.0, 66.0, 66.0, 68.0], "value": 83.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 85.0, 85.0, 82.0, 85.0, 81.0, 86.0, 85.0, 87.0, 84.0], "value": 84.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 144.0, 140.0, 139.0, 164.0, 160.0, 160.0, 143.0, 175.0, 171.0], "value": 153.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 176.0, 171.0, 172.0, 234.0, 220.0, 169.0, 169.0, 173.0, 177.0], "value": 185.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 70.0, 69.0, 72.0, 71.0, 71.0, 69.0, 74.0, 62.0, 81.0], "value": 71.0, "unit": "score"}], "name": "kraken", "value": 1651.8000000000002, "alertThreshold": 2.0}]} 16:41:26 INFO - Return code: 0 16:41:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:41:26 ERROR - # TBPL SUCCESS # 16:41:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:41:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:41:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:41:26 INFO - 2016-04-21 16:41:26.182 screenresolution[1684:10301] starting screenresolution argv=screenresolution get 16:41:26 INFO - 2016-04-21 16:41:26.185 screenresolution[1684:10301] Display 0: 1600x1200x32@60 16:41:26 INFO - 2016-04-21 16:41:26.191 screenresolution[1685:10304] starting screenresolution argv=screenresolution list 16:41:26 INFO - Available Modes on Display 0 16:41:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:41:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:41:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:41:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:41:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:41:26 INFO - Intel Iris: 16:41:26 INFO - Chipset Model: Intel Iris 16:41:26 INFO - Type: GPU 16:41:26 INFO - Bus: Built-In 16:41:26 INFO - VRAM (Dynamic, Max): 1536 MB 16:41:26 INFO - Vendor: Intel (0x8086) 16:41:26 INFO - Device ID: 0x0a2e 16:41:26 INFO - Revision ID: 0x0009 16:41:26 INFO - Displays: 16:41:26 INFO - Display: 16:41:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:41:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:41:26 INFO - Display Serial Number: 1600x1200 60 16:41:26 INFO - Main Display: Yes 16:41:26 INFO - Mirror: Off 16:41:26 INFO - Online: Yes 16:41:26 INFO - Rotation: Supported 16:41:26 INFO - Return code: 0 16:41:26 INFO - Running post-action listener: _resource_record_post_action 16:41:26 INFO - Running post-run listener: _resource_record_post_run 16:41:27 INFO - Total resource usage - Wall time: 1208s; CPU: 54.0%; Read bytes: 36450816; Write bytes: 695634944; Read time: 383; Write time: 1920 16:41:27 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 373649920; Write bytes: 367798272; Read time: 28063; Write time: 631 16:41:27 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 35488256; Write bytes: 298406912; Read time: 366; Write time: 1233 16:41:27 INFO - Running post-run listener: _upload_blobber_files 16:41:27 INFO - Blob upload gear active. 16:41:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:41:27 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 16:41:27 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:41:27 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:41:27 INFO - (blobuploader) - INFO - Open directory for files ... 16:41:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:41:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:41:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:41:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:41:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:41:29 INFO - (blobuploader) - INFO - Done attempting. 16:41:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:41:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:41:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:41:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:41:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:41:30 INFO - (blobuploader) - INFO - Done attempting. 16:41:30 INFO - (blobuploader) - INFO - Iteration through files over. 16:41:30 INFO - Return code: 0 16:41:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:41:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:41:30 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f7406163f0834834067de45299084a4940e8e1c6cc1aef99ebaa1c73b7e5b9e6bd5f78b3d203515b305aaf6e045a6526cf1e70051e152343d89a2e2ef2e037e6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f440b4926ecca6e6709d40568abe294cabc77127a29825d6eef418a8da9f267b5f3c3427f33a566b076a51017a27b161c8b9e7dc22638e0711e308d162bd9ac0"} 16:41:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:41:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:41:30 INFO - Contents: 16:41:30 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f7406163f0834834067de45299084a4940e8e1c6cc1aef99ebaa1c73b7e5b9e6bd5f78b3d203515b305aaf6e045a6526cf1e70051e152343d89a2e2ef2e037e6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f440b4926ecca6e6709d40568abe294cabc77127a29825d6eef418a8da9f267b5f3c3427f33a566b076a51017a27b161c8b9e7dc22638e0711e308d162bd9ac0"} 16:41:30 INFO - Running post-run listener: copy_logs_to_upload_dir 16:41:30 INFO - Copying logs to upload dir... 16:41:30 INFO - mkdir: /builds/slave/test/build/upload/logs 16:41:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.729999 ========= master_lag: 3.34 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-04-21 16:41:33.378512) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-21 16:41:33.382180) ========= 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.OEZMWScpxd/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.yycfIiOqIF/Listeners TMPDIR=/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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/Firefox/sha512/f7406163f0834834067de45299084a4940e8e1c6cc1aef99ebaa1c73b7e5b9e6bd5f78b3d203515b305aaf6e045a6526cf1e70051e152343d89a2e2ef2e037e6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f440b4926ecca6e6709d40568abe294cabc77127a29825d6eef418a8da9f267b5f3c3427f33a566b076a51017a27b161c8b9e7dc22638e0711e308d162bd9ac0"} build_url:https://queue.taskcluster.net/v1/task/fKS3xRVXT_2xF-eilOH6FA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011376 build_url: 'https://queue.taskcluster.net/v1/task/fKS3xRVXT_2xF-eilOH6FA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f7406163f0834834067de45299084a4940e8e1c6cc1aef99ebaa1c73b7e5b9e6bd5f78b3d203515b305aaf6e045a6526cf1e70051e152343d89a2e2ef2e037e6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f440b4926ecca6e6709d40568abe294cabc77127a29825d6eef418a8da9f267b5f3c3427f33a566b076a51017a27b161c8b9e7dc22638e0711e308d162bd9ac0"}' ========= master_lag: 0.18 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-21 16:41:33.572146) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 16:41:33.572480) ========= 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.OEZMWScpxd/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.yycfIiOqIF/Listeners TMPDIR=/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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.005264 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-21 16:41:33.613141) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-21 16:41:33.613469) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-21 16:41:33.613929) ========= ========= Total master_lag: 3.62 =========