builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0059 starttime: 1461616286.2 results: success (0) buildid: 20160425103006 builduid: 1a87fa8d522747ab8b0da4138352f4b4 revision: 0d6a91c76a9eddf2219caf028dea130f2d683ec2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-25 13:31:26.198575) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-25 13:31:26.199038) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-25 13:31:26.199354) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-25 13:31:27.114502) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 13:31:27.114832) ========= 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.ITvbMDdJgX/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.f2s9Yv6Clz/Listeners TMPDIR=/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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-25 13:31:27-- 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% 148M=0s 2016-04-25 13:31:27 (148 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.584209 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 13:31:27.719128) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 13:31:27.719552) ========= 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.ITvbMDdJgX/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.f2s9Yv6Clz/Listeners TMPDIR=/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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.020490 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 13:31:27.769530) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-25 13:31:27.769917) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 0d6a91c76a9eddf2219caf028dea130f2d683ec2 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 0d6a91c76a9eddf2219caf028dea130f2d683ec2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ITvbMDdJgX/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.f2s9Yv6Clz/Listeners TMPDIR=/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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-25 13:31:27,829 truncating revision to first 12 chars 2016-04-25 13:31:27,829 Setting DEBUG logging. 2016-04-25 13:31:27,829 attempt 1/10 2016-04-25 13:31:27,829 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/0d6a91c76a9e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-25 13:31:28,579 unpacking tar archive at: mozilla-aurora-0d6a91c76a9e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.005335 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-25 13:31:28.792987) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-25 13:31:28.793306) ========= script_repo_revision: 0d6a91c76a9eddf2219caf028dea130f2d683ec2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-25 13:31:28.793690) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-25 13:31:28.793967) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-25 13:31:28.808354) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-04-25 13:31:28.808721) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ITvbMDdJgX/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.f2s9Yv6Clz/Listeners TMPDIR=/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 13:31:28 INFO - MultiFileLogger online at 20160425 13:31:28 in /builds/slave/test 13:31:28 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 13:31:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:31:28 INFO - {'append_to_log': False, 13:31:28 INFO - 'base_work_dir': '/builds/slave/test', 13:31:28 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 13:31:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:31:28 INFO - 'branch': 'Mozilla-Aurora', 13:31:28 INFO - 'buildbot_json_path': 'buildprops.json', 13:31:28 INFO - 'config_files': ('talos/mac_config.py',), 13:31:28 INFO - 'default_actions': ('clobber', 13:31:28 INFO - 'read-buildbot-config', 13:31:28 INFO - 'download-and-extract', 13:31:28 INFO - 'populate-webroot', 13:31:28 INFO - 'create-virtualenv', 13:31:28 INFO - 'install', 13:31:28 INFO - 'run-tests'), 13:31:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:31:28 INFO - 'download_minidump_stackwalk': True, 13:31:28 INFO - 'download_symbols': 'ondemand', 13:31:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:31:28 INFO - 'tooltool.py': '/tools/tooltool.py', 13:31:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:31:28 INFO - '/tools/misc-python/virtualenv.py')}, 13:31:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:31:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:31:28 INFO - 'installer_path': 'installer.exe', 13:31:28 INFO - 'log_level': 'info', 13:31:28 INFO - 'log_name': 'talos', 13:31:28 INFO - 'log_to_console': True, 13:31:28 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:31:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:31:28 INFO - 'opt_config_files': (), 13:31:28 INFO - 'pip_index': False, 13:31:28 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:31:28 INFO - 'cmd': ('bash', 13:31:28 INFO - '-c', 13:31:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:31:28 INFO - 'enabled': True, 13:31:28 INFO - 'halt_on_failure': False, 13:31:28 INFO - 'name': 'check_screen_resolution'},), 13:31:28 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:31:28 INFO - 'cmd': ('bash', 13:31:28 INFO - '-c', 13:31:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:31:28 INFO - 'enabled': True, 13:31:28 INFO - 'halt_on_failure': False, 13:31:28 INFO - 'name': 'check_screen_resolution'},), 13:31:28 INFO - 'run_cmd_checks_enabled': True, 13:31:28 INFO - 'sps_profile': False, 13:31:28 INFO - 'sps_profile_interval': 0, 13:31:28 INFO - 'suite': 'dromaeojs-e10s', 13:31:28 INFO - 'system_bits': '32', 13:31:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:31:28 INFO - 'title': 't-yosemite-r7-0059', 13:31:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:31:28 INFO - 'use_talos_json': True, 13:31:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:31:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:31:28 INFO - 'work_dir': 'build'} 13:31:28 INFO - ##### 13:31:28 INFO - ##### Running clobber step. 13:31:28 INFO - ##### 13:31:28 INFO - Running pre-action listener: _resource_record_pre_action 13:31:28 INFO - Running main action method: clobber 13:31:28 INFO - rmtree: /builds/slave/test/build 13:31:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:31:30 INFO - Running post-action listener: _resource_record_post_action 13:31:30 INFO - ##### 13:31:30 INFO - ##### Running read-buildbot-config step. 13:31:30 INFO - ##### 13:31:30 INFO - Running pre-action listener: _resource_record_pre_action 13:31:30 INFO - Running main action method: read_buildbot_config 13:31:30 INFO - Using buildbot properties: 13:31:30 INFO - { 13:31:30 INFO - "project": "", 13:31:30 INFO - "product": "firefox", 13:31:30 INFO - "script_repo_revision": "production", 13:31:30 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 13:31:30 INFO - "repository": "", 13:31:30 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 13:31:30 INFO - "buildid": "20160425103006", 13:31:30 INFO - "pgo_build": "False", 13:31:30 INFO - "basedir": "/builds/slave/test", 13:31:30 INFO - "buildnumber": 296, 13:31:30 INFO - "slavename": "t-yosemite-r7-0059", 13:31:30 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 13:31:30 INFO - "platform": "yosemite_r7", 13:31:30 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 13:31:30 INFO - "branch": "mozilla-aurora", 13:31:30 INFO - "repo_path": "releases/mozilla-aurora", 13:31:30 INFO - "revision": "0d6a91c76a9eddf2219caf028dea130f2d683ec2", 13:31:30 INFO - "stage_platform": "macosx64", 13:31:30 INFO - "builduid": "1a87fa8d522747ab8b0da4138352f4b4", 13:31:30 INFO - "slavebuilddir": "test" 13:31:30 INFO - } 13:31:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 13:31:30 INFO - Running post-action listener: _resource_record_post_action 13:31:30 INFO - ##### 13:31:30 INFO - ##### Running download-and-extract step. 13:31:30 INFO - ##### 13:31:30 INFO - Running pre-action listener: _resource_record_pre_action 13:31:30 INFO - Running main action method: download_and_extract 13:31:30 INFO - mkdir: /builds/slave/test/build/tests 13:31:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:31:30 INFO - https://queue.taskcluster.net/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 13:31:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 13:31:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 13:31:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 13:31:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 13:31:30 INFO - Downloaded 1199 bytes. 13:31:30 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 13:31:30 INFO - Using the following test package requirements: 13:31:30 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 13:31:30 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:31:30 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 13:31:30 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:31:30 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 13:31:30 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:31:30 INFO - u'jsshell-mac.zip'], 13:31:30 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:31:30 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 13:31:30 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 13:31:30 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:31:30 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 13:31:30 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:31:30 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 13:31:30 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:31:30 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 13:31:30 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:31:30 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 13:31:30 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 13:31:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:31:30 INFO - https://queue.taskcluster.net/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:31:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 13:31:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 13:31:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 13:31:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 13:31:32 INFO - Downloaded 18109567 bytes. 13:31:32 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:31:32 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 13:31:35 INFO - Return code: 0 13:31:35 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 13:31:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:31:35 INFO - https://queue.taskcluster.net/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:31:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 13:31:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 13:31:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 13:31:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 13:31:36 INFO - Downloaded 18109567 bytes. 13:31:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:31:36 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 13:31:40 INFO - Return code: 0 13:31:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:31:40 INFO - https://queue.taskcluster.net/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 13:31:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 13:31:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 13:31:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 13:31:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 13:31:41 INFO - Downloaded 10840145 bytes. 13:31:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:31:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 13:31:41 INFO - Return code: 0 13:31:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:31:41 INFO - https://queue.taskcluster.net/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 13:31:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 13:31:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 13:31:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 13:31:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 13:31:45 INFO - Downloaded 114074910 bytes. 13:31:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 13:31:45 INFO - mkdir: /builds/slave/test/properties 13:31:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:31:45 INFO - Writing to file /builds/slave/test/properties/build_url 13:31:45 INFO - Contents: 13:31:45 INFO - build_url:https://queue.taskcluster.net/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 13:31:45 INFO - Running post-action listener: _resource_record_post_action 13:31:45 INFO - Running post-action listener: set_extra_try_arguments 13:31:45 INFO - ##### 13:31:45 INFO - ##### Running populate-webroot step. 13:31:45 INFO - ##### 13:31:45 INFO - Running pre-action listener: _resource_record_pre_action 13:31:45 INFO - Running main action method: populate_webroot 13:31:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:31:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:31:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:31:45 INFO - '--tppagecycles', 13:31:45 INFO - '7'], 13:31:45 INFO - 'tests': ['tsvgm']}}, 13:31:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:31:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:31:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:31:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:31:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:31:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:31:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:31:45 INFO - 'talos_options': ['--disable-e10s'], 13:31:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:31:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:31:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:31:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:31:45 INFO - 'talos_options': ['--disable-e10s'], 13:31:45 INFO - 'tests': ['damp', 'tps']}, 13:31:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:31:45 INFO - 'tests': ['damp', 'tps']}, 13:31:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:31:45 INFO - 'tests': ['dromaeo_dom']}, 13:31:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:31:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:31:45 INFO - 'tests': ['a11yr', 13:31:45 INFO - 'ts_paint', 13:31:45 INFO - 'tpaint', 13:31:45 INFO - 'sessionrestore', 13:31:45 INFO - 'sessionrestore_no_auto_restore', 13:31:45 INFO - 'tabpaint']}, 13:31:45 INFO - 'other-e10s': {'tests': ['a11yr', 13:31:45 INFO - 'ts_paint', 13:31:45 INFO - 'tpaint', 13:31:45 INFO - 'sessionrestore', 13:31:45 INFO - 'sessionrestore_no_auto_restore', 13:31:45 INFO - 'tabpaint']}, 13:31:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:31:45 INFO - 'ts_paint', 13:31:45 INFO - 'tpaint', 13:31:45 INFO - 'sessionrestore', 13:31:45 INFO - 'sessionrestore_no_auto_restore', 13:31:45 INFO - 'tabpaint']}, 13:31:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:31:45 INFO - 'ts_paint', 13:31:45 INFO - 'tpaint', 13:31:45 INFO - 'sessionrestore', 13:31:45 INFO - 'sessionrestore_no_auto_restore', 13:31:45 INFO - 'tabpaint']}, 13:31:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:31:45 INFO - 'tests': ['a11yr', 13:31:45 INFO - 'ts_paint', 13:31:45 INFO - 'tpaint', 13:31:45 INFO - 'sessionrestore', 13:31:45 INFO - 'sessionrestore_no_auto_restore', 13:31:45 INFO - 'tabpaint']}, 13:31:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:31:45 INFO - 'tests': ['a11yr', 13:31:45 INFO - 'ts_paint', 13:31:45 INFO - 'tpaint', 13:31:45 INFO - 'sessionrestore', 13:31:45 INFO - 'sessionrestore_no_auto_restore', 13:31:45 INFO - 'tabpaint']}, 13:31:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:31:45 INFO - 'tests': ['tsvgx', 13:31:45 INFO - 'tsvgr_opacity', 13:31:45 INFO - 'tart', 13:31:45 INFO - 'tscrollx', 13:31:45 INFO - 'cart']}, 13:31:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:31:45 INFO - 'tsvgr_opacity', 13:31:45 INFO - 'tart', 13:31:45 INFO - 'tscrollx', 13:31:45 INFO - 'cart']}, 13:31:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:31:45 INFO - 'talos_options': ['--disable-e10s'], 13:31:45 INFO - 'tests': ['tp5o']}, 13:31:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:31:45 INFO - 'tests': ['tp5o']}, 13:31:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:31:45 INFO - 'talos_options': ['--disable-e10s', 13:31:45 INFO - '--xperf_path', 13:31:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:31:45 INFO - 'tests': ['tp5n']}, 13:31:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:31:45 INFO - 'talos_options': ['--xperf_path', 13:31:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:31:45 INFO - 'tests': ['tp5n']}}, 13:31:45 INFO - 'talos.zip': {'path': '', 13:31:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:31:45 INFO - Running post-action listener: _resource_record_post_action 13:31:45 INFO - ##### 13:31:45 INFO - ##### Running create-virtualenv step. 13:31:45 INFO - ##### 13:31:45 INFO - Running pre-action listener: _resource_record_pre_action 13:31:45 INFO - Running main action method: create_virtualenv 13:31:45 INFO - Creating virtualenv /builds/slave/test/build/venv 13:31:45 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 13:31:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:31:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:31:46 INFO - Using real prefix '/tools/python27' 13:31:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:31:46 INFO - Installing distribute.............................................................................................................................................................................................done. 13:31:49 INFO - Installing pip.................done. 13:31:49 INFO - Return code: 0 13:31:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:31:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:31:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:31:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:49 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 0x106023a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106033420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa659d8cbb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ec2e00>, '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 0x105d2bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ebdb20>, '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.ITvbMDdJgX/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.f2s9Yv6Clz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:31:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 13:31:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 13:31:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ITvbMDdJgX/Render', 13:31:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:31:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:31:49 INFO - 'HOME': '/Users/cltbld', 13:31:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:31:49 INFO - 'LOGNAME': 'cltbld', 13:31:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:49 INFO - 'MOZ_NO_REMOTE': '1', 13:31:49 INFO - 'NO_EM_RESTART': '1', 13:31:49 INFO - 'PAGER': '/bin/cat', 13:31:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:31:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:49 INFO - 'PWD': '/builds/slave/test', 13:31:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:31:49 INFO - 'SHELL': '/bin/bash', 13:31:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f2s9Yv6Clz/Listeners', 13:31:49 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 13:31:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:31:49 INFO - 'USER': 'cltbld', 13:31:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:31:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:31:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:49 INFO - 'XPC_FLAGS': '0x0', 13:31:49 INFO - 'XPC_SERVICE_NAME': '0', 13:31:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:31:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:49 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 13:31:49 INFO - Cleaning up... 13:31:49 INFO - Return code: 0 13:31:49 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 13:31:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:31:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:31:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:49 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 0x106023a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106033420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa659d8cbb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ec2e00>, '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 0x105d2bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ebdb20>, '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.ITvbMDdJgX/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.f2s9Yv6Clz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:31:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 13:31:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 13:31:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ITvbMDdJgX/Render', 13:31:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:31:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:31:49 INFO - 'HOME': '/Users/cltbld', 13:31:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:31:49 INFO - 'LOGNAME': 'cltbld', 13:31:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:49 INFO - 'MOZ_NO_REMOTE': '1', 13:31:49 INFO - 'NO_EM_RESTART': '1', 13:31:49 INFO - 'PAGER': '/bin/cat', 13:31:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:31:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:49 INFO - 'PWD': '/builds/slave/test', 13:31:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:31:49 INFO - 'SHELL': '/bin/bash', 13:31:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f2s9Yv6Clz/Listeners', 13:31:49 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 13:31:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:31:49 INFO - 'USER': 'cltbld', 13:31:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:31:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:31:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:49 INFO - 'XPC_FLAGS': '0x0', 13:31:49 INFO - 'XPC_SERVICE_NAME': '0', 13:31:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:31:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:50 INFO - Downloading/unpacking psutil>=0.7.1 13:31:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:31:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:31:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:31:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:31:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:31:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:31:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:31:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:31:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:31:52 INFO - Installing collected packages: psutil 13:31:52 INFO - Running setup.py install for psutil 13:31:52 INFO - building 'psutil._psutil_osx' extension 13:31:52 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 13:31:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 13:31:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 13:31:53 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 13:31:53 INFO - building 'psutil._psutil_posix' extension 13:31:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 13:31:53 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:31:53 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:31:53 INFO - ^ 13:31:53 INFO - 1 warning generated. 13:31:53 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 13:31:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:31:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:31:53 INFO - Successfully installed psutil 13:31:53 INFO - Cleaning up... 13:31:53 INFO - Return code: 0 13:31:53 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 13:31:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:31:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:31:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:53 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 0x106023a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106033420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa659d8cbb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ec2e00>, '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 0x105d2bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ebdb20>, '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.ITvbMDdJgX/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.f2s9Yv6Clz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:31:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 13:31:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 13:31:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ITvbMDdJgX/Render', 13:31:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:31:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:31:53 INFO - 'HOME': '/Users/cltbld', 13:31:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:31:53 INFO - 'LOGNAME': 'cltbld', 13:31:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:53 INFO - 'MOZ_NO_REMOTE': '1', 13:31:53 INFO - 'NO_EM_RESTART': '1', 13:31:53 INFO - 'PAGER': '/bin/cat', 13:31:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:31:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:53 INFO - 'PWD': '/builds/slave/test', 13:31:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:31:53 INFO - 'SHELL': '/bin/bash', 13:31:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f2s9Yv6Clz/Listeners', 13:31:53 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 13:31:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:31:53 INFO - 'USER': 'cltbld', 13:31:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:31:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:31:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:53 INFO - 'XPC_FLAGS': '0x0', 13:31:53 INFO - 'XPC_SERVICE_NAME': '0', 13:31:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:31:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:53 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 13:31:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:31:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:31:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:31:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:31:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:31:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:31:56 INFO - Downloading mozsystemmonitor-0.0.tar.gz 13:31:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:31:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 13:31:56 INFO - Installing collected packages: mozsystemmonitor 13:31:56 INFO - Running setup.py install for mozsystemmonitor 13:31:56 INFO - Successfully installed mozsystemmonitor 13:31:56 INFO - Cleaning up... 13:31:56 INFO - Return code: 0 13:31:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:31:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:31:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:31:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:31:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:31:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:31:56 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 0x106023a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106033420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa659d8cbb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ec2e00>, '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 0x105d2bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ebdb20>, '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.ITvbMDdJgX/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.f2s9Yv6Clz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:31:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 13:31:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 13:31:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ITvbMDdJgX/Render', 13:31:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:31:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:31:56 INFO - 'HOME': '/Users/cltbld', 13:31:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:31:56 INFO - 'LOGNAME': 'cltbld', 13:31:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:56 INFO - 'MOZ_NO_REMOTE': '1', 13:31:56 INFO - 'NO_EM_RESTART': '1', 13:31:56 INFO - 'PAGER': '/bin/cat', 13:31:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:31:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:56 INFO - 'PWD': '/builds/slave/test', 13:31:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:31:56 INFO - 'SHELL': '/bin/bash', 13:31:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f2s9Yv6Clz/Listeners', 13:31:56 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 13:31:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:31:56 INFO - 'USER': 'cltbld', 13:31:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:31:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:31:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:56 INFO - 'XPC_FLAGS': '0x0', 13:31:56 INFO - 'XPC_SERVICE_NAME': '0', 13:31:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:31:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:31:56 INFO - Downloading/unpacking blobuploader==1.2.4 13:31:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:31:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:31:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:31:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:31:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:31:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:31:59 INFO - Downloading blobuploader-1.2.4.tar.gz 13:31:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:31:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:31:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:31:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:31:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:31:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:31:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:31:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:31:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:32:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:32:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:32:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:32:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:32:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:32:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:32:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:32:00 INFO - Downloading docopt-0.6.1.tar.gz 13:32:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:32:00 INFO - Installing collected packages: blobuploader, requests, docopt 13:32:00 INFO - Running setup.py install for blobuploader 13:32:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:32:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:32:00 INFO - Running setup.py install for requests 13:32:01 INFO - Running setup.py install for docopt 13:32:01 INFO - Successfully installed blobuploader requests docopt 13:32:01 INFO - Cleaning up... 13:32:01 INFO - Return code: 0 13:32:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:32:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:32:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:32:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:32:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:32:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:32:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:32:01 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 0x106023a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106033420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa659d8cbb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ec2e00>, '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 0x105d2bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ebdb20>, '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.ITvbMDdJgX/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.f2s9Yv6Clz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:32:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 13:32:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:32:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ITvbMDdJgX/Render', 13:32:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:32:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:32:01 INFO - 'HOME': '/Users/cltbld', 13:32:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:32:01 INFO - 'LOGNAME': 'cltbld', 13:32:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:32:01 INFO - 'MOZ_NO_REMOTE': '1', 13:32:01 INFO - 'NO_EM_RESTART': '1', 13:32:01 INFO - 'PAGER': '/bin/cat', 13:32:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:32:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:32:01 INFO - 'PWD': '/builds/slave/test', 13:32:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:32:01 INFO - 'SHELL': '/bin/bash', 13:32:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f2s9Yv6Clz/Listeners', 13:32:01 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 13:32:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:32:01 INFO - 'USER': 'cltbld', 13:32:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:32:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:32:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:32:01 INFO - 'XPC_FLAGS': '0x0', 13:32:01 INFO - 'XPC_SERVICE_NAME': '0', 13:32:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:32:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:32:01 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-PMkwa1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:32:01 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-rO8kMg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:32:01 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-JNOF3a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:32:02 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-26ddSo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:32:02 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-3NHfZY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:32:02 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-Ty2Cc0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:32:02 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-w_oBUH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:32:02 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-VPPPLZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:32:02 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-UX80jb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:32:02 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-0HNKZ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:32:02 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-4J3uqZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:32:02 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-ZfdIzD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:32:03 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-7zsdFn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:32:03 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-V_lI6B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:32:03 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-haqWTp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:32:03 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-Zj7G1E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:32:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:32:03 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-HwUgyZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:32:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:32:03 INFO - Running setup.py install for manifestparser 13:32:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:32:03 INFO - Running setup.py install for mozcrash 13:32:03 INFO - Running setup.py install for mozdebug 13:32:03 INFO - Running setup.py install for mozdevice 13:32:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:32:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:32:04 INFO - Running setup.py install for mozfile 13:32:04 INFO - Running setup.py install for mozhttpd 13:32:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:32:04 INFO - Running setup.py install for mozinfo 13:32:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:32:04 INFO - Running setup.py install for mozInstall 13:32:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:32:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:32:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:32:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:32:04 INFO - Running setup.py install for mozleak 13:32:04 INFO - Running setup.py install for mozlog 13:32:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:32:05 INFO - Running setup.py install for moznetwork 13:32:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:32:05 INFO - Running setup.py install for mozprocess 13:32:05 INFO - Running setup.py install for mozprofile 13:32:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:32:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:32:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:32:05 INFO - Running setup.py install for mozrunner 13:32:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:32:05 INFO - Running setup.py install for mozscreenshot 13:32:05 INFO - Running setup.py install for moztest 13:32:05 INFO - Running setup.py install for mozversion 13:32:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:32:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:32:06 INFO - Cleaning up... 13:32:06 INFO - Return code: 0 13:32:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:32:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:32:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:32:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:32:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:32:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:32:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:32:06 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 0x106023a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106033420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa659d8cbb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ec2e00>, '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 0x105d2bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ebdb20>, '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.ITvbMDdJgX/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.f2s9Yv6Clz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:32:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 13:32:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:32:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ITvbMDdJgX/Render', 13:32:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:32:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:32:06 INFO - 'HOME': '/Users/cltbld', 13:32:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:32:06 INFO - 'LOGNAME': 'cltbld', 13:32:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:32:06 INFO - 'MOZ_NO_REMOTE': '1', 13:32:06 INFO - 'NO_EM_RESTART': '1', 13:32:06 INFO - 'PAGER': '/bin/cat', 13:32:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:32:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:32:06 INFO - 'PWD': '/builds/slave/test', 13:32:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:32:06 INFO - 'SHELL': '/bin/bash', 13:32:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f2s9Yv6Clz/Listeners', 13:32:06 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 13:32:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:32:06 INFO - 'USER': 'cltbld', 13:32:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:32:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:32:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:32:06 INFO - 'XPC_FLAGS': '0x0', 13:32:06 INFO - 'XPC_SERVICE_NAME': '0', 13:32:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:32:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:32:06 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-W1cdDU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:32:06 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 13:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:32:06 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-01cKF1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:32:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:32:06 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-yq1qGJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:32:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 13:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:32:06 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-YVsUql-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:32:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:32:06 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-a4baBK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:32:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 13:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:32:06 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-ocCJhq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:32:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 13:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:32:07 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-54Ja__-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:32:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 13:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:32:07 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-OxkDYy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:32:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 13:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:32:07 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-4xmtN2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:32:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 13:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:32:07 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-Rx9Vdr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:32:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:32:07 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-WkOPO9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:32:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 13:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:32:07 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-NwCtVv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:32:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 13:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:32:07 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-iGbLhA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:32:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 13:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:32:07 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-tkb2af-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:32:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 13:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:32:07 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-Y6uO2Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:32:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 13:32:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:32:08 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-gFn_zx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:32:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 13:32:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:32:08 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-r2wIt2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:32:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 13:32:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:32:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:32:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 13:32:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:32:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:32:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:32:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:32:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:32:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:32:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:32:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:32:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:32:10 INFO - Downloading blessings-1.6.tar.gz 13:32:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:32:10 INFO - Installing collected packages: blessings 13:32:10 INFO - Running setup.py install for blessings 13:32:10 INFO - Successfully installed blessings 13:32:10 INFO - Cleaning up... 13:32:11 INFO - Return code: 0 13:32:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:32:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:32:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:32:11 INFO - Reading from file tmpfile_stdout 13:32:11 INFO - Current package versions: 13:32:11 INFO - blessings == 1.6 13:32:11 INFO - blobuploader == 1.2.4 13:32:11 INFO - docopt == 0.6.1 13:32:11 INFO - manifestparser == 1.1 13:32:11 INFO - mozInstall == 1.12 13:32:11 INFO - mozcrash == 0.17 13:32:11 INFO - mozdebug == 0.1 13:32:11 INFO - mozdevice == 0.48 13:32:11 INFO - mozfile == 1.2 13:32:11 INFO - mozhttpd == 0.7 13:32:11 INFO - mozinfo == 0.9 13:32:11 INFO - mozleak == 0.1 13:32:11 INFO - mozlog == 3.1 13:32:11 INFO - moznetwork == 0.27 13:32:11 INFO - mozprocess == 0.22 13:32:11 INFO - mozprofile == 0.28 13:32:11 INFO - mozrunner == 6.11 13:32:11 INFO - mozscreenshot == 0.1 13:32:11 INFO - mozsystemmonitor == 0.0 13:32:11 INFO - moztest == 0.7 13:32:11 INFO - mozversion == 1.4 13:32:11 INFO - psutil == 3.1.1 13:32:11 INFO - requests == 1.2.3 13:32:11 INFO - wsgiref == 0.1.2 13:32:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:32:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:32:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:32:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:32:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:32:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:32:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:32:11 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 0x106023a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106033420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa659d8cbb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ec2e00>, '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 0x105d2bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ebdb20>, '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.ITvbMDdJgX/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.f2s9Yv6Clz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:32:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 13:32:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:32:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ITvbMDdJgX/Render', 13:32:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:32:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:32:11 INFO - 'HOME': '/Users/cltbld', 13:32:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:32:11 INFO - 'LOGNAME': 'cltbld', 13:32:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:32:11 INFO - 'MOZ_NO_REMOTE': '1', 13:32:11 INFO - 'NO_EM_RESTART': '1', 13:32:11 INFO - 'PAGER': '/bin/cat', 13:32:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:32:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:32:11 INFO - 'PWD': '/builds/slave/test', 13:32:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:32:11 INFO - 'SHELL': '/bin/bash', 13:32:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f2s9Yv6Clz/Listeners', 13:32:11 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 13:32:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:32:11 INFO - 'USER': 'cltbld', 13:32:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:32:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:32:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:32:11 INFO - 'XPC_FLAGS': '0x0', 13:32:11 INFO - 'XPC_SERVICE_NAME': '0', 13:32:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:32:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:32:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 13:32:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 13:32:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 13:32:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 13:32:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 13:32:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 13:32:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 13:32:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 13:32:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 13:32:11 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 13:32:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 13:32:11 INFO - Cleaning up... 13:32:11 INFO - Return code: 0 13:32:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:32:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:32:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:32:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:32:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:32:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:32:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:32:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106023a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106033420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa659d8cbb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ec2e00>, '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 0x105d2bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ebdb20>, '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.ITvbMDdJgX/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.f2s9Yv6Clz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:32:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 13:32:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 13:32:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ITvbMDdJgX/Render', 13:32:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:32:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:32:11 INFO - 'HOME': '/Users/cltbld', 13:32:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:32:11 INFO - 'LOGNAME': 'cltbld', 13:32:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:32:11 INFO - 'MOZ_NO_REMOTE': '1', 13:32:11 INFO - 'NO_EM_RESTART': '1', 13:32:11 INFO - 'PAGER': '/bin/cat', 13:32:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:32:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:32:11 INFO - 'PWD': '/builds/slave/test', 13:32:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:32:11 INFO - 'SHELL': '/bin/bash', 13:32:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f2s9Yv6Clz/Listeners', 13:32:11 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 13:32:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:32:11 INFO - 'USER': 'cltbld', 13:32:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:32:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:32:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:32:11 INFO - 'XPC_FLAGS': '0x0', 13:32:11 INFO - 'XPC_SERVICE_NAME': '0', 13:32:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:32:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:32:11 INFO - Downloading/unpacking jsonschema 13:32:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:32:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:32:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:32:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:32:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:32:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:32:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:32:14 INFO - Downloading/unpacking functools32 (from jsonschema) 13:32:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:32:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:32:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:32:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:32:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:32:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:32:14 INFO - Downloading functools32-3.2.3-2.tar.gz 13:32:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:32:14 INFO - warning: no files found matching '*.txt' 13:32:14 INFO - no previously-included directories found matching 'build' 13:32:14 INFO - no previously-included directories found matching 'dist' 13:32:14 INFO - no previously-included directories found matching '.git*' 13:32:14 INFO - Installing collected packages: jsonschema, functools32 13:32:14 INFO - Running setup.py install for functools32 13:32:14 INFO - warning: no files found matching '*.txt' 13:32:14 INFO - no previously-included directories found matching 'build' 13:32:14 INFO - no previously-included directories found matching 'dist' 13:32:14 INFO - no previously-included directories found matching '.git*' 13:32:14 INFO - Successfully installed jsonschema functools32 13:32:14 INFO - Cleaning up... 13:32:15 INFO - Return code: 0 13:32:15 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 13:32:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:32:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:32:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:32:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:32:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:32:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:32:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106023a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106033420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa659d8cbb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ec2e00>, '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 0x105d2bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ebdb20>, '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.ITvbMDdJgX/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.f2s9Yv6Clz/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:32:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 13:32:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 13:32:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ITvbMDdJgX/Render', 13:32:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:32:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:32:15 INFO - 'HOME': '/Users/cltbld', 13:32:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:32:15 INFO - 'LOGNAME': 'cltbld', 13:32:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:32:15 INFO - 'MOZ_NO_REMOTE': '1', 13:32:15 INFO - 'NO_EM_RESTART': '1', 13:32:15 INFO - 'PAGER': '/bin/cat', 13:32:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:32:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:32:15 INFO - 'PWD': '/builds/slave/test', 13:32:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:32:15 INFO - 'SHELL': '/bin/bash', 13:32:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f2s9Yv6Clz/Listeners', 13:32:15 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 13:32:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:32:15 INFO - 'USER': 'cltbld', 13:32:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:32:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:32:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:32:15 INFO - 'XPC_FLAGS': '0x0', 13:32:15 INFO - 'XPC_SERVICE_NAME': '0', 13:32:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:32:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:32:15 INFO - Downloading/unpacking flake8 13:32: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 13:32: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 13:32: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 13:32: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 13:32: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 13:32: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 13:32:17 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 13:32:17 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 13:32:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:32:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:32:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:32:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:32:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:32:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:32:18 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 13:32:18 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 13:32:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:32:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:32:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:32:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:32:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:32:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:32:18 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 13:32:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:32:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:32:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:32:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:32:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:32:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:32:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 13:32:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:32:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:32:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:32:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:32:19 INFO - no previously-included directories found matching 'docs/_build' 13:32:19 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 13:32:19 INFO - Running setup.py install for pep8 13:32:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:32:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:32:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:32:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:32:19 INFO - no previously-included directories found matching 'docs/_build' 13:32:19 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 13:32:19 INFO - Successfully installed flake8 mccabe pyflakes pep8 13:32:19 INFO - Cleaning up... 13:32:19 INFO - Return code: 0 13:32:19 INFO - Running post-action listener: _resource_record_post_action 13:32:19 INFO - Running post-action listener: _start_resource_monitoring 13:32:19 INFO - Starting resource monitoring. 13:32:19 INFO - ##### 13:32:19 INFO - ##### Running install step. 13:32:19 INFO - ##### 13:32:19 INFO - Running pre-action listener: _resource_record_pre_action 13:32:19 INFO - Running main action method: install 13:32:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:32:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:32:19 INFO - Reading from file tmpfile_stdout 13:32:19 INFO - Detecting whether we're running mozinstall >=1.0... 13:32:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:32:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:32:19 INFO - Reading from file tmpfile_stdout 13:32:19 INFO - Output received: 13:32:19 INFO - Usage: mozinstall [options] installer 13:32:19 INFO - Options: 13:32:19 INFO - -h, --help show this help message and exit 13:32:19 INFO - -d DEST, --destination=DEST 13:32:19 INFO - Directory to install application into. [default: 13:32:19 INFO - "/builds/slave/test"] 13:32:19 INFO - --app=APP Application being installed. [default: firefox] 13:32:19 INFO - mkdir: /builds/slave/test/build/application 13:32:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 13:32:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 13:32:51 INFO - Reading from file tmpfile_stdout 13:32:51 INFO - Output received: 13:32:51 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 13:32:51 INFO - Running post-action listener: _resource_record_post_action 13:32:51 INFO - ##### 13:32:51 INFO - ##### Running run-tests step. 13:32:51 INFO - ##### 13:32:51 INFO - Running pre-action listener: _resource_record_pre_action 13:32:51 INFO - Running main action method: run_tests 13:32:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:32:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:32:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:32:51 INFO - 2016-04-25 13:32:51.855 screenresolution[2852:130247] starting screenresolution argv=screenresolution get 13:32:51 INFO - 2016-04-25 13:32:51.858 screenresolution[2852:130247] Display 0: 1600x1200x32@60 13:32:51 INFO - 2016-04-25 13:32:51.864 screenresolution[2853:130249] starting screenresolution argv=screenresolution list 13:32:51 INFO - Available Modes on Display 0 13:32:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:32:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:32:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:32:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:32:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:32:52 INFO - Intel Iris: 13:32:52 INFO - Chipset Model: Intel Iris 13:32:52 INFO - Type: GPU 13:32:52 INFO - Bus: Built-In 13:32:52 INFO - VRAM (Dynamic, Max): 1536 MB 13:32:52 INFO - Vendor: Intel (0x8086) 13:32:52 INFO - Device ID: 0x0a2e 13:32:52 INFO - Revision ID: 0x0009 13:32:52 INFO - Displays: 13:32:52 INFO - Display: 13:32:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:32:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:32:52 INFO - Display Serial Number: 1600x1200 60 13:32:52 INFO - Main Display: Yes 13:32:52 INFO - Mirror: Off 13:32:52 INFO - Online: Yes 13:32:52 INFO - Rotation: Supported 13:32:52 INFO - Return code: 0 13:32:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:32:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:32:52 INFO - Python 2.7.3 13:32:52 INFO - Return code: 0 13:32:52 INFO - grabbing minidump binary from tooltool 13:32:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:32:52 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 0x105ec2e00>, '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 0x105d2bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105ebdb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:32:52 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 13:32:52 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 13:32:52 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:32:52 INFO - Return code: 0 13:32:52 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:32:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:32:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:32:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:32:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:32:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 13:32:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 13:32:53 INFO - Return code: 0 13:32:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0059', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 13:32:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0059 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 13:32:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ITvbMDdJgX/Render', 13:32:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:32:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:32:53 INFO - 'HOME': '/Users/cltbld', 13:32:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:32:53 INFO - 'LOGNAME': 'cltbld', 13:32:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:32:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:32:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:32:53 INFO - 'MOZ_NO_REMOTE': '1', 13:32:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:32:53 INFO - 'NO_EM_RESTART': '1', 13:32:53 INFO - 'PAGER': '/bin/cat', 13:32:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:32:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:32:53 INFO - 'PWD': '/builds/slave/test', 13:32:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:32:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:32:53 INFO - 'SHELL': '/bin/bash', 13:32:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f2s9Yv6Clz/Listeners', 13:32:53 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 13:32:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:32:53 INFO - 'USER': 'cltbld', 13:32:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:32:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:32:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:32:53 INFO - 'XPC_FLAGS': '0x0', 13:32:53 INFO - 'XPC_SERVICE_NAME': '0', 13:32:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:32:53 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0059', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 13:32:53 INFO - mozversion application_buildid: 20160425103006 13:32:53 INFO - mozversion application_changeset: 0d6a91c76a9eddf2219caf028dea130f2d683ec2 13:32:53 INFO - mozversion application_display_name: FirefoxDeveloperEdition 13:32:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:32:53 INFO - mozversion application_name: Firefox 13:32:53 INFO - mozversion application_remotingname: firefox-dev 13:32:53 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:32:53 INFO - mozversion application_vendor: Mozilla 13:32:53 INFO - mozversion application_version: 48.0a2 13:32:53 INFO - mozversion platform_buildid: 20160425103006 13:32:53 INFO - mozversion platform_changeset: 0d6a91c76a9eddf2219caf028dea130f2d683ec2 13:32:53 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:32:53 INFO - mozversion platform_version: 48.0a2 13:32:53 INFO - using testdate: 1461616373 13:32:53 INFO - actual date: 1461616373 13:32:53 INFO - starting webserver on 'localhost:61706' 13:32:53 INFO - SUITE-START | Running 2 tests 13:32:53 INFO - TEST-START | dromaeo_css 13:32:53 INFO - operating with platform_type : mac_ 13:32:53 INFO - Initialising browser for dromaeo_css test... 13:32:53 INFO - TEST-INFO | started process 2871 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/tmpyOQlQH/profile http://localhost:61706/getInfo.html) 13:32:56 INFO - PROCESS | 2871 | __metrics Screen width/height:1600/1200 13:32:56 INFO - PROCESS | 2871 | colorDepth:24 13:32:56 INFO - PROCESS | 2871 | Browser inner width/height: 1024/689 13:32:56 INFO - PROCESS | 2871 | __metrics 13:32:56 ERROR - PROCESS | 2871 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 13:32:57 INFO - TEST-INFO | 2871: exit 0 13:32:57 INFO - Browser initialized. 13:32:57 INFO - Running cycle 1/1 for dromaeo_css test... 13:32:57 INFO - TEST-INFO | started process 2874 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/tmpyOQlQH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:33:09 INFO - PROCESS | 2874 | RSS: Main: 221667328 13:33:09 INFO - PROCESS | 2874 | 13:33:59 INFO - PROCESS | 2874 | 1461616439010 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 13:36:10 INFO - PROCESS | 2874 | Cycle 1(1): loaded http://localhost:61706/tests/dromaeo/cssquery-dojo.html (next: http://localhost:61706/tests/dromaeo/cssquery-ext.html) 13:36:11 INFO - PROCESS | 2874 | RSS: Main: 235335680 13:36:11 INFO - PROCESS | 2874 | 13:39:13 INFO - PROCESS | 2874 | Cycle 1(1): loaded http://localhost:61706/tests/dromaeo/cssquery-ext.html (next: http://localhost:61706/tests/dromaeo/cssquery-jquery.html) 13:39:14 INFO - PROCESS | 2874 | RSS: Main: 246837248 13:39:14 INFO - PROCESS | 2874 | 13:42:15 INFO - PROCESS | 2874 | Cycle 1(1): loaded http://localhost:61706/tests/dromaeo/cssquery-jquery.html (next: http://localhost:61706/tests/dromaeo/cssquery-mootools.html) 13:42:16 INFO - PROCESS | 2874 | RSS: Main: 248578048 13:42:16 INFO - PROCESS | 2874 | 13:45:18 INFO - PROCESS | 2874 | Cycle 1(1): loaded http://localhost:61706/tests/dromaeo/cssquery-mootools.html (next: http://localhost:61706/tests/dromaeo/cssquery-prototype.html) 13:45:18 INFO - PROCESS | 2874 | RSS: Main: 250429440 13:45:18 INFO - PROCESS | 2874 | 13:48:21 INFO - PROCESS | 2874 | Cycle 1(1): loaded http://localhost:61706/tests/dromaeo/cssquery-prototype.html (next: http://localhost:61706/tests/dromaeo/cssquery-yui.html) 13:48:22 INFO - PROCESS | 2874 | RSS: Main: 253411328 13:48:22 INFO - PROCESS | 2874 | 13:51:24 INFO - PROCESS | 2874 | Cycle 1(1): loaded http://localhost:61706/tests/dromaeo/cssquery-yui.html (next: http://localhost:61706/tests/dromaeo/cssquery-dojo.html) 13:51:24 INFO - PROCESS | 2874 | RSS: Main: 254640128 13:51:24 INFO - PROCESS | 2874 | 13:51:24 INFO - PROCESS | 2874 | __start_tp_report 13:51:24 INFO - PROCESS | 2874 | _x_x_mozilla_page_load 13:51:24 INFO - PROCESS | 2874 | _x_x_mozilla_page_load_details 13:51:24 INFO - PROCESS | 2874 | |i|pagename|runs| 13:51:24 INFO - PROCESS | 2874 | |0;dojo.html;3297.7032067113964;3467.306538692261;3478.808665523396;3495.667911548402;3538.310029544238;5661.886762264752;5668.121441176619;5670.4896559309655;5713;5721.227634269378;5694.487496125348;5717.971410142944;5751.597388182829;5753.367129615739;5767.9423205768035;5172.301739265207;5174.560162386183;5191.325127733389;5240.633155679104;5240.9213861792105;5145.202493613496;5219.373675158986;5233;5328.227407025998;5379.5696344292555;4810.855674329776;4922.950770492285;5209.609279304067;5226.7647955841885;5240.44975277596;4378.40891479651;4849.757512124415;5080.872978175538;5203.219517072432;5233.712145831978;4976.701397916137;5164.096283150434;5168.8449346519665;5179.896402071938;5200.8179713710215;5691.943080569183;5722.685252311121;5729.770809167629;5748.310202775652;5772.624779389326;6095.451409373133;6218.906716399258;6230.376962303733;6278.4663303619245;6287.402696743802;5032.974835125801;6143.603994442171;6146.66193359365;6317.715702793385;6325.240971083499;4695.5304469552775;5820.417958204188;5827.388124246935;5832.212651292064;5839.445252700986;6300.779472718433;6308.684565771693;6311.936880631227;6340.651264180515;6366.395192456709;6091.9695401523595;6109.4653969264045;6123.28262147438;6179.196704428396;6188.8762224755255;6304.432601065926;6324.936750632435;6328.968355158195;6337.746490140343;6347.904781428282;6242.28213755424;6265.843353916098;6273.686315684197;6291.937080629135;6297.874042519215;5073.594112470993;6113.658745237732;6263.467605253604;6294.622322660655;6312.558120931582;4823.493533179036;4904;4929.285253638274;4942.5304596063315;4960.181570040902;3789.621037896188;3959.544652364957;4062.3297155968935;4344.1311737652595;4461.174682683649;5696.572757043238;5737.741801618854;5746.798862039892;5749.396313387034;5759.683217423082;6241.34465881085;6241.407066328691;6258.249010118724;6261.373862613793;6276.309605943343;4684.8360307389075;5594.328680558359;5685.971570142123;5691.800786972436;5717.142428635738;5408.459154084639;5425.891482170414;5441.374241962146;5461.726913654381;5472.2338872557875;6365.172527571293;6379.393957574023;6380.55336126476;6380.808575742735;6398.776042838478;6178.9382106180165;6235.345288744798;6270.686465676789;6344.841378965562;6361.109444677842;6314.273858506331;6346.428821406095;6363.681815909279;6370.490360771035;6375.840603984752;4808.471068182427;6161.938380616137;6238.563300568917;6261.874762504815;6283.874322513616;6129.328227221949;6202.472789812917;6279.058141278845;6288.339724329063;6319.842003949938;6137.478314343152;6146.631202127886;6149.446549810538;6150.815475535741;6155.938440615716;6112.694365281807;6151.046587778901;6169.475594574512;6169.691515424301;6173.59871608344;4967.807726145771;5762.135679648086;5852.531797456279;5967.791127310524;6037.69811509414;6124;6131.816045518642;6151.630902145886;6167.907481387872;6181.629102253879;5824.475797178275;6045.153678484928;6073.0283154695035;6093.360197179232;6098.908516372347 13:51:24 INFO - PROCESS | 2874 | |1;ext.html;479.9928001079982;527.0302643136629;528.8149147798271;555.84714203594;565.8755073883744;38457.84626461207;39429;39479.0130246744;39555.406668899916;39687.60312396875;41197.17605647885;41683.58316416835;42074.158516829724;42167.734967950935;42209.78895105528;9485.905140948571;9682.757931051745;9716.805663886718;9732.41605503672;9740.659076932308;9561.521923903812;9742.318037737361;9743.123118919295;9750.609975601003;9762.170215531689;9686.854697179548;9695.418274903528;9712.028797120267;9726.2705297103;9758.658446954358;9663.806723865519;9733.56198971048;9740.4642744649;9748.561314740853;9753.90246097537;9552.614870843758;9641.469719165443;9673.951630241874;9686;9695.418274903528;39372.803135984424;39604.20791584152;39738.006549836195;39769.00577485557;39791.00522486932;2606.7002294736158;2763.8203516771546;3110.833437460952;3215.115843143131;3284.490903909874;26521.734782652118;27406.5889011665;27612.447751044867;27693.723062769517;28535;2703.297142742901;2740.904068357598;2764.2812868654096;2772.1683494951635;2788.512010398172;377986.1100694479;433817;440041;441471;441493;34483.82758086194;35109.12227194289;35166.82416587901;35999.64000360015;36258.09354766099;35459.4681079784;35864.924052278475;35994.820025900226;36087.09782255465;36298.45552316718;14292.714145717027;15048.699026019638;15601.453949111727;15618.843811561967;16176.352945882269;13202.33988300581;13203.735925281633;13445.865541344461;13583.796243056362;13589.52436664712;8218.465799722999;8261.710840120568;8292.875606865902;8309.169083091643;8337.291330236876;3363.882264120744;3401.982990085083;3402.5066365376883;3413.7951722896705;3423.623401425841;931.6040682709821;973.6835528453281;1005.6982905128432;1027.0961553832728;1154.8152295632658;25863;30773.69226307753;30927.91752288704;31076;31185.376292474022;1139.635316698665;1173.524722487394;1188.1326631559011;1215.598852378713;1230.2864338683542;12863.614091577268;15980.76028859568;16037.11795851216;16147.114168798747;16183.514494565183;575863;662944;669236;672358;680150;54668;61700;62639.373606263354;62873.05690414648;63157.36842631515;1307.6338625184962;1476.7268055409784;1499.9625009374636;1501.4369611395728;1501.9474318398804;30187.396252074395;30640.84679576588;30749.692503074682;30777.692223077484;31190.532142018343;42821.1435771289;52049.21926171035;52363;52760;53052.73473632607;483375;585989;587882;588482;589086;27371.31571710723;27632.723672763015;27833;27947.301317467227;28066.7369968356;42773.57226427696;44297.55702443063;44424.77787611041;44718.329225061;44744.776276119715;30094.548581771727;38335.80832095933;38370.2325953485;39072.41391379188;39101.217975640895;36527;36765;36900.63099369079;37019.62980370269;37190.44214336733;31014.914477993163;35903.820480897426;36466.45300320444;37248.06879828026;37295;31045.844770776002;32885;33634.831825840716;33792;33977.83011084929;25404.364890877874;25686.486270274865;25709.228723138334;25982;25995.610065849407 13:51:24 INFO - PROCESS | 2874 | |2;jquery.html;357.8336073725717;399.7961039869667;402.7643828360409;406.1470911086718;412.1715352142195;20377.898110509446;21062.89468552655;21177.364679059632;21213.681794773092;21663.566728665417;20430.387088387335;20802;21703.131874724993;22252;22435.663465048037;5216.217567364906;5834.795782147626;6077.4226448487325;6184.319724830265;6186.288576813657;6033.849153771147;6059.151718759377;6140.416660417253;6145.692715364236;6151.169592105053;5916.437938395867;5988.8203353899235;6027.487663548603;6040.154378387029;6066.817995460143;5784.1034639630925;6029.035354343306;6065.939340606604;6146.078088286748;6152.230971128609;5883.676397798119;5939.4357536034;5949.167116603679;5955.672438015908;5968.731407086691;24321.270361889172;25156.587123231577;25431.364215894566;26009.21972340814;26513.204603861723;2144.9249276275254;2337.0418128567376;2351.1888398502488;2522.3063657494154;2611.3341098019882;9928.50357482123;10701.785964280749;13931.930340348234;14807.407703691839;15794.183668878111;1591.2759694339047;1665.5752783040368;1723.758673785671;1724.6205834716343;1756.7277072053853;128200.3589982044;129146.7085329144;132084.33957830246;136533.3173334127;137972;11877.22798018126;12017.939910300567;12046.759064818654;12142.514299427923;12209.145359824905;15456.38174473031;15496.147711875956;15543.378264869505;15548.22258887051;15549.84450155484;9150.176484116462;9159.587818548182;9201.447913125234;9218.769530761785;9339.813203735886;7323.853522929511;7493.288137626917;8284.840122382871;9035.819283614292;9059.773505662411;3880.553736320303;4138.689598280081;4703.318018887242;4732.485604606492;4736.076465089274;1704.343827626348;1957.2366776956999;1989.44295597233;2012.3962811156887;2022.1506967073865;891.8439273127177;936.1200471556715;965.8647789309503;1002.8746406699163;1004.1514919892679;17847.863094704942;21263.93680315978;21363;21391.46521336948;21716.89141554282;826.7023871406288;1003.3979612232602;1006.3609607899036;1024.71820249432;1029.7940411917646;10845.403502807263;11224.214304998572;11282.266652667551;11318.773624527463;11346.886531134583;613856;616495;620426;627851;631970;76269.47461050747;87860;102589;143062;150951;996.1980605612407;998.670438755209;1012.3015119567475;1023.9606799098921;1028.1466382902322;18523.90738046301;18615.81384186195;18746.250149993848;18748.156332964776;19001.334953276568;50130.74934625303;52909;55811;56158.43841561532;56688.43311566996;78529.2147078522;79955;79963;80096.19903801121;82047;7437.8140546486775;8356.080831108451;8411.621477033426;8531.658733650584;8898.332625053017;7687.308142267223;9591.952040239754;11526.59656912004;12937.870621294043;13127.934360328138;11348;11467.770644586895;11503.654890353302;11646.475908584303;11681.88318116808;11221.158413118885;11254;11351.716207094558;11474.311541307548;11655.59205427806;6131.938680613315;8535.53054582004;11563.36401497892;11753.353565553976;11841.407929603312;8315.251627353568;8450.957745211235;8555.486670799772;8589.14108589136;8702.869456958024;8407.747767566983;10263.127634150882;11072.446377681244;11195.2723073;11445.599404020819 13:51:24 INFO - PROCESS | 2874 | |3;mootools.html;1603.5991002249439;1630.7798447209627;1866.5613580808508;1930.4208737378792;1955.3840540229817;8129.349652027839;8646.308295336374;8771.956140219298;9137.131972462605;9162.816743665131;7385.556866588008;8753.255973242281;8774.254188393978;8848.823023539526;8853.026167121612;2443.5601591713485;2444.8288619796667;2450.4363996280867;2477.8513289202615;2505.912293069743;2084.853330668134;2244.5623103494827;2302.1942320187886;2313.467902382453;2336.8247381446376;2442.3894026493376;2460.4218008767925;2470.9258722238274;2487.340854673513;2495.1641200197864;2451.2156110044793;2459.0655550890724;2461.9507609847883;2468.691413573303;2471.233917485585;2445.437549363656;2459;2464.6056630938965;2473.1591258972035;2473.282748003077;8870.37907346486;8975.640974361018;8992.190702836777;9004.144606262395;9028.683996060172;5303.761330740126;5482.753276102585;5558.833235002957;5597.44025597441;5625.8593535161535;3647.960331305565;3648.3797754381817;3653.561572611277;3734.5518537775642;3737.9065523362133;1044.6134930075846;1049.7323182588468;1057.9947100264526;1059.8304271316629;1079.0827796373098;11725.589604363806;11835.644930652094;11914.523419063315;11972.281663100242;11989.400529973467;4256.744595324229;4794;4867.318575399447;4954.455009948902;4954.950450495449;10015.69952901414;10107.343022703648;10117.190624750003;10120.240981926385;10125.291229614004;5414.10667239901;5443.8094666686475;5495.9832431000395;5569.053260945649;5636.633618814764;4438.800253988578;4834.95165048352;5009.849704508871;5012.373453318335;5070.29015937769;4022.6379625833815;4033.153037862082;4118.608732170439;4172.019575399779;4179.477565304337;1176.7937863689615;1337.7190789934027;1359.5581436033328;1360.7550640884735;1371.4994027180007;880.3309484791605;932.3731281714819;942.6842007927421;951.4386511957978;984.2568860510269;5194.597458686134;5629.761452480448;5843.29880414353;5928.851778705481;6026.096085587197;430.3716573802284;434.46343765449643;459.0978726801784;461.1767994130461;465.4205514134924;4002.5597184310286;4033.173199494155;4050.675945924319;4055.8986025349013;4089.325261331847;9987.650432234837;10433.373997560018;10507.00183482568;10944.507497162647;11007.339559626447;10724.946375268073;10812.945935270272;10820.4048777318;10888.29226100301;10993.835092473619;7909.932159158555;8091.757247282591;8184.140665230184;8192.057913340042;8265.008199016036;5867.823965281048;5867.9413205869105;5893.499052580407;5953.821385358447;5972.641641501524;9890.109890109925;9903.50482475859;10042.748931276776;10086.293959423063;10086.8486972694;9864.950675246579;10250.38497690141;10262.025107614765;10322.329048611817;10483.842742358718;6874;7321.816954576178;7490.213527579525;7579.886301705368;7646.617669116411;8449.66201351939;8465.873011904703;8537.231649151357;8568.871466927876;8571;7321.707131714672;7510.737124200627;7554.320111190019;7564.205758395289;7639.121501027453;6524;7175.712971481083;7248.528845625017;7289.161746399232;7357.374623157091;6945.409640180439;7000.579965202104;7032.1912980007955;7038.472114591528;7061.6822243000215;4472.127935052634;4779.235322348408;4928.728919909439;4997.300377947017;5175.094358487175;4942.555170034714;5018.5232402921665;5033;5057.468965758542;5206.97396513015 13:51:24 INFO - PROCESS | 2874 | |4;prototype.html;286.22147758097975;306.7699225580814;318.29338867713676;318.56515855856765;319.89763275751767;4141.813618387172;4822.348982887309;5431.049566325892;5459.290292262011;5515.972420137899;5174;5286.762095705692;5533.806316778913;5544.750486228119;5558.166275058743;4237.978810105953;4243.7056697707185;4245.214635292481;4252.681048921328;4267.743935363873;3913.6477717005464;4210.705165684709;4233.470816147981;4269.8078586463525;4272.46594175728;4077.979610101945;4112.97943510282;4128.401381799637;4130.483689538808;4168.956661637007;3587.551556055493;3634.854605815764;4114.732542384751;4137.77242251676;4172.853950111731;3622.836972336251;3624.3476174288617;3627.365211088049;3650.2881938022097;3665.651763082503;4397.988462653576;4909.484504127051;5001.624878134155;5118.309028281209;5255.74003178633;4175.498940127204;4693.855117071536;4724.67709041469;4729.03744945847;4744.4069491313585;5141.974290128563;5396.64921780083;5692.601517893749;5837.562182836262;5849.081115652523;3780.092777733352;3937.9015524611827;3968.5634580196156;3968.583298753647;3984.4222587724907;5574.60977731559;5824.796132135397;5970.0746384310505;6118.694065296718;6213.782517611907;3738.364478038686;4015.638592526687;4015.975926138816;4017.995501124719;4032.213718324957;3415.64135765746;3708.851645934132;3745.6628903398823;4092.5907409258834;4110.506739191256;4170.812313445842;4197.853075142355;4747.098051370229;4762.92834112322;4774.1883879739835;4664.883377915579;4998.425181104147;5157.406898206679;5939.406059394026;5942.7920022799;1299.402274953532;1316.2497376495305;1449.326063380533;1520.6426489774894;1556.4630202580092;949.4730424614268;961.1205746741792;1000.2748007694508;1000.8740167311774;1024.7284469615558;5126.92309615356;5478.945210547843;5752.194692743019;5773.278340207474;5808.850725231607;5707.85955266775;6167.64311851392;6565.5227573796465;6572.14562106923;6595.670216489157;5532.917006244909;5768.711564421846;5783.8554036149435;5813.715250883238;5888.293404791367;2944.7349738523635;3393.7285017198565;3399.473081672345;3449.4998225257195;3452.861885524551;6040.244969378828;6319.96840015797;6791.219009813836;6795.38841504267;6813.727450902007;4931.312434723013;5048.823291184791;5365.892682146336;6222.937770622236;6257.811015906963;5349.528879558153;5830.416958304136;6086.543509236825;6385.850546901603;6393.6483493408305;4917;5590.217369568345;5731.283589551306;6096.969515152573;6114.676422959247;5314.867128321823;6003;6018.638881667114;6025.8852112359355;6054.757809687563;4343.392944610514;4630.259158534618;5706.686132262599;6171.290301615371;6391.01878417692;3527.7354198434705;3827.8851634449898;3959.247742928834;3965.841366345314;4010.57888921659;4577.793999269974;5924.822255332347;6020.157177994996;6080.847978800389;6090.69546522681;5187.170052791535;5915.37888521699;5928.67392293428;5929.99190137669;5974.520573961757;5360.892782144258;5848.502877255481;5885.323187833454;5909.645421274738;5949.91075133882;5086.237064440364;5401.459854014567;5714.742836572281;5720.971395142998;5774.942250577608;5868.354481007171;5898.94101058984;5899.793507227227;5910.674912879833;5921.792737254175;5277.261183434399;5299.5760339172;5544.306961629796;5615.894039735099;5800.477956983892 13:51:24 INFO - PROCESS | 2874 | |5;yui.html;680.9795706128816;681.6251061915945;695.4922906278416;704.84845758162;758.2455456820464;14651.608097230772;14864.925675371622;14879.958402911787;14900.776488352683;15086.773698394532;10819.513121909513;13733.45066197351;13763.243021633805;13765.518206862762;15079.62300942477;767.9155292917775;882.9249513791335;915.0757734687963;926.3561824531953;933.8785957825507;839.9160083991613;902.5667679513838;902.715644571961;911.3165126155384;930.1303281431849;968.903109689029;972.2270794718181;973.459729849933;974.2790335152007;976.448306706712;949.8100379923974;961.9038096190395;965.1217392173124;969.9078587534173;971.6793458158791;701.5650296815955;716.6166101135867;717.7631381644045;723.0889079759488;723.6960476599841;14983.625409364744;15034.774478382833;15202.315895784606;15282.465113720966;15282.923585382003;12077.396130193456;12287.815682764765;12302.569410070604;12474.18917770351;12508.061895357794;10123.595056197742;10134.493275336205;10245.436500992442;10351.637692680795;10453.059224669816;3430.5711786026745;3481.3559491494157;3490.4240800267926;3536.681698647134;3543.6279190684863;79490.20509794886;79885;82085;82320;83367.5831620838;9110.27117830572;9236.122568355995;9260.675876344296;9419.858702119474;9434.575444105032;21484.24805131813;21508.139674412847;21833.454163646034;21845.453863653536;21883.452913676967;7270.091238595176;7466.962665186639;7472.29013243741;7492.438067144986;7553.962230188924;4059.4316795648633;4063.918721625551;4064.6545043671617;4137.958620413818;4142.047329114261;3550.218951830593;3588.102974256436;3621.8008009559726;3633.65480279373;3636.3454578176184;641.6182371488957;654.7348323928817;657.7796438193259;664.2659860853784;675.3955210086945;560.1849309255024;591.9200907877467;595.487880422836;599.7870755881686;610.5146408605169;8424.957875210708;9051.773705657279;9054.366194366332;9068.637254509878;9144.131307525775;525.3485677759551;557.9916301255485;593.8307582339099;606.3572613030202;636.6402982314977;3879.1465877507467;4014.578469260686;4069.8168582413864;4078.469798926121;4218.374649880902;90164.09835901688;93650.53174734082;94063.58904616436;94273.0572694278;99927;81030;82802.58598706967;83500.58249708713;84454.57772711097;85867.57066214629;76903;77387.83918241231;77551.44897102026;77688;78050;7622.961885190539;8196.508209507449;8306.501609903426;8371.95814020926;8507.914920850711;47155;47211.52788472209;47264.34574789866;47389.526104738514;47755;63533.68233158805;65146.34853651592;66015.67968640696;66153.66923165353;66222.67554648976;10837.645294338208;10967.50646220938;11032.172587055842;11151.052160566443;11238.943805280922;11981.880181198076;11994.400279986141;12152.453139608915;12201.14591978553;12529;11086.501107450345;11342.716432089264;11510.59712910044;11664.94167529157;11671.649850504498;11364.374959377314;11388.202825802115;11432.885671143182;11651.30092194471;11954.163208575315;8627.396082274432;9623.374480658735;10411.271211015157;10790.784184316426;11669;8369.95815020921;9529.857052144362;9691.321607487691;9876.40741555509;10025.548850301608;9600.279979001743;9700.466474343979;9717.56270967794;9746.366486178376;9763.974782648003 13:51:24 INFO - PROCESS | 2874 | __end_tp_report 13:51:24 INFO - PROCESS | 2874 | __start_cc_report 13:51:24 INFO - PROCESS | 2874 | _x_x_mozilla_cycle_collect,420 13:51:24 INFO - PROCESS | 2874 | __end_cc_report 13:51:24 INFO - PROCESS | 2874 | __startTimestamp1461617484704__endTimestamp 13:51:24 INFO - PROCESS | 2874 | 13:51:24 INFO - PROCESS | 2874 | ------- Summary: start ------- 13:51:24 INFO - PROCESS | 2874 | Number of tests: 6 13:51:24 INFO - PROCESS | 2874 | 13:51:24 INFO - PROCESS | 2874 | [#0] dojo.html Cycles:165 Average:5712.05 Median:5967.79 stddev:696.23 (11.7%) stddev-sans-first:672.10 13:51:24 INFO - PROCESS | 2874 | Values: 3297.7 3467.3 3478.8 3495.7 3538.3 5661.9 5668.1 5670.5 5713.0 5721.2 5694.5 5718.0 5751.6 5753.4 5767.9 5172.3 5174.6 5191.3 5240.6 5240.9 5145.2 5219.4 5233.0 5328.2 5379.6 4810.9 4923.0 5209.6 5226.8 5240.4 4378.4 4849.8 5080.9 5203.2 5233.7 4976.7 5164.1 5168.8 5179.9 5200.8 5691.9 5722.7 5729.8 5748.3 5772.6 6095.5 6218.9 6230.4 6278.5 6287.4 5033.0 6143.6 6146.7 6317.7 6325.2 4695.5 5820.4 5827.4 5832.2 5839.4 6300.8 6308.7 6311.9 6340.7 6366.4 6092.0 6109.5 6123.3 6179.2 6188.9 6304.4 6324.9 6329.0 6337.7 6347.9 6242.3 6265.8 6273.7 6291.9 6297.9 5073.6 6113.7 6263.5 6294.6 6312.6 4823.5 4904.0 4929.3 4942.5 4960.2 3789.6 3959.5 4062.3 4344.1 4461.2 5696.6 5737.7 5746.8 5749.4 5759.7 6241.3 6241.4 6258.2 6261.4 6276.3 4684.8 5594.3 5686.0 5691.8 5717.1 5408.5 5425.9 5441.4 5461.7 5472.2 6365.2 6379.4 6380.6 6380.8 6398.8 6178.9 6235.3 6270.7 6344.8 6361.1 6314.3 6346.4 6363.7 6370.5 6375.8 4808.5 6161.9 6238.6 6261.9 6283.9 6129.3 6202.5 6279.1 6288.3 6319.8 6137.5 6146.6 6149.4 6150.8 6155.9 6112.7 6151.0 6169.5 6169.7 6173.6 4967.8 5762.1 5852.5 5967.8 6037.7 6124.0 6131.8 6151.6 6167.9 6181.6 5824.5 6045.2 6073.0 6093.4 6098.9 13:51:24 INFO - PROCESS | 2874 | 13:51:24 INFO - PROCESS | 2874 | [#1] ext.html Cycles:180 Average:66461.61 Median:37105.04 stddev:149474.62 (402.8%) stddev-sans-first:149811.84 13:51:24 INFO - PROCESS | 2874 | Values: 480.0 527.0 528.8 555.8 565.9 38457.8 39429.0 39479.0 39555.4 39687.6 41197.2 41683.6 42074.2 42167.7 42209.8 9485.9 9682.8 9716.8 9732.4 9740.7 9561.5 9742.3 9743.1 9750.6 9762.2 9686.9 9695.4 9712.0 9726.3 9758.7 9663.8 9733.6 9740.5 9748.6 9753.9 9552.6 9641.5 9674.0 9686.0 9695.4 39372.8 39604.2 39738.0 39769.0 39791.0 2606.7 2763.8 3110.8 3215.1 3284.5 26521.7 27406.6 27612.4 27693.7 28535.0 2703.3 2740.9 2764.3 2772.2 2788.5 377986.1 433817.0 440041.0 441471.0 441493.0 34483.8 35109.1 35166.8 35999.6 36258.1 35459.5 35864.9 35994.8 36087.1 36298.5 14292.7 15048.7 15601.5 15618.8 16176.4 13202.3 13203.7 13445.9 13583.8 13589.5 8218.5 8261.7 8292.9 8309.2 8337.3 3363.9 3402.0 3402.5 3413.8 3423.6 931.6 973.7 1005.7 1027.1 1154.8 25863.0 30773.7 30927.9 31076.0 31185.4 1139.6 1173.5 1188.1 1215.6 1230.3 12863.6 15980.8 16037.1 16147.1 16183.5 575863.0 662944.0 669236.0 672358.0 680150.0 54668.0 61700.0 62639.4 62873.1 63157.4 1307.6 1476.7 1500.0 1501.4 1501.9 30187.4 30640.8 30749.7 30777.7 31190.5 42821.1 52049.2 52363.0 52760.0 53052.7 483375.0 585989.0 587882.0 588482.0 589086.0 27371.3 27632.7 27833.0 27947.3 28066.7 42773.6 44297.6 44424.8 44718.3 44744.8 30094.5 38335.8 38370.2 39072.4 39101.2 36527.0 36765.0 36900.6 37019.6 37190.4 31014.9 35903.8 36466.5 37248.1 37295.0 31045.8 32885.0 33634.8 33792.0 33977.8 25404.4 25686.5 25709.2 25982.0 25995.6 13:51:24 INFO - PROCESS | 2874 | 13:51:24 INFO - PROCESS | 2874 | [#2] jquery.html Cycles:180 Average:35925.41 Median:13839.45 stddev:103735.83 (749.6%) stddev-sans-first:103992.46 13:51:24 INFO - PROCESS | 2874 | Values: 357.8 399.8 402.8 406.1 412.2 20377.9 21062.9 21177.4 21213.7 21663.6 20430.4 20802.0 21703.1 22252.0 22435.7 5216.2 5834.8 6077.4 6184.3 6186.3 6033.8 6059.2 6140.4 6145.7 6151.2 5916.4 5988.8 6027.5 6040.2 6066.8 5784.1 6029.0 6065.9 6146.1 6152.2 5883.7 5939.4 5949.2 5955.7 5968.7 24321.3 25156.6 25431.4 26009.2 26513.2 2144.9 2337.0 2351.2 2522.3 2611.3 9928.5 10701.8 13931.9 14807.4 15794.2 1591.3 1665.6 1723.8 1724.6 1756.7 128200.4 129146.7 132084.3 136533.3 137972.0 11877.2 12017.9 12046.8 12142.5 12209.1 15456.4 15496.1 15543.4 15548.2 15549.8 9150.2 9159.6 9201.4 9218.8 9339.8 7323.9 7493.3 8284.8 9035.8 9059.8 3880.6 4138.7 4703.3 4732.5 4736.1 1704.3 1957.2 1989.4 2012.4 2022.2 891.8 936.1 965.9 1002.9 1004.2 17847.9 21263.9 21363.0 21391.5 21716.9 826.7 1003.4 1006.4 1024.7 1029.8 10845.4 11224.2 11282.3 11318.8 11346.9 613856.0 616495.0 620426.0 627851.0 631970.0 76269.5 87860.0 102589.0 143062.0 150951.0 996.2 998.7 1012.3 1024.0 1028.1 18523.9 18615.8 18746.3 18748.2 19001.3 50130.7 52909.0 55811.0 56158.4 56688.4 78529.2 79955.0 79963.0 80096.2 82047.0 7437.8 8356.1 8411.6 8531.7 8898.3 7687.3 9592.0 11526.6 12937.9 13127.9 11348.0 11467.8 11503.7 11646.5 11681.9 11221.2 11254.0 11351.7 11474.3 11655.6 6131.9 8535.5 11563.4 11753.4 11841.4 8315.3 8451.0 8555.5 8589.1 8702.9 8407.7 10263.1 11072.4 11195.3 11445.6 13:51:24 INFO - PROCESS | 2874 | 13:51:24 INFO - PROCESS | 2874 | [#3] mootools.html Cycles:180 Average:5759.37 Median:4814.48 stddev:3223.20 (66.9%) stddev-sans-first:3217.12 13:51:24 INFO - PROCESS | 2874 | Values: 1603.6 1630.8 1866.6 1930.4 1955.4 8129.3 8646.3 8772.0 9137.1 9162.8 7385.6 8753.3 8774.3 8848.8 8853.0 2443.6 2444.8 2450.4 2477.9 2505.9 2084.9 2244.6 2302.2 2313.5 2336.8 2442.4 2460.4 2470.9 2487.3 2495.2 2451.2 2459.1 2462.0 2468.7 2471.2 2445.4 2459.0 2464.6 2473.2 2473.3 8870.4 8975.6 8992.2 9004.1 9028.7 5303.8 5482.8 5558.8 5597.4 5625.9 3648.0 3648.4 3653.6 3734.6 3737.9 1044.6 1049.7 1058.0 1059.8 1079.1 11725.6 11835.6 11914.5 11972.3 11989.4 4256.7 4794.0 4867.3 4954.5 4955.0 10015.7 10107.3 10117.2 10120.2 10125.3 5414.1 5443.8 5496.0 5569.1 5636.6 4438.8 4835.0 5009.8 5012.4 5070.3 4022.6 4033.2 4118.6 4172.0 4179.5 1176.8 1337.7 1359.6 1360.8 1371.5 880.3 932.4 942.7 951.4 984.3 5194.6 5629.8 5843.3 5928.9 6026.1 430.4 434.5 459.1 461.2 465.4 4002.6 4033.2 4050.7 4055.9 4089.3 9987.7 10433.4 10507.0 10944.5 11007.3 10724.9 10812.9 10820.4 10888.3 10993.8 7909.9 8091.8 8184.1 8192.1 8265.0 5867.8 5867.9 5893.5 5953.8 5972.6 9890.1 9903.5 10042.7 10086.3 10086.8 9865.0 10250.4 10262.0 10322.3 10483.8 6874.0 7321.8 7490.2 7579.9 7646.6 8449.7 8465.9 8537.2 8568.9 8571.0 7321.7 7510.7 7554.3 7564.2 7639.1 6524.0 7175.7 7248.5 7289.2 7357.4 6945.4 7000.6 7032.2 7038.5 7061.7 4472.1 4779.2 4928.7 4997.3 5175.1 4942.6 5018.5 5033.0 5057.5 5207.0 13:51:24 INFO - PROCESS | 2874 | 13:51:24 INFO - PROCESS | 2874 | [#4] prototype.html Cycles:180 Average:4729.40 Median:5165.70 stddev:1469.83 (28.5%) stddev-sans-first:1435.62 13:51:24 INFO - PROCESS | 2874 | Values: 286.2 306.8 318.3 318.6 319.9 4141.8 4822.3 5431.0 5459.3 5516.0 5174.0 5286.8 5533.8 5544.8 5558.2 4238.0 4243.7 4245.2 4252.7 4267.7 3913.6 4210.7 4233.5 4269.8 4272.5 4078.0 4113.0 4128.4 4130.5 4169.0 3587.6 3634.9 4114.7 4137.8 4172.9 3622.8 3624.3 3627.4 3650.3 3665.7 4398.0 4909.5 5001.6 5118.3 5255.7 4175.5 4693.9 4724.7 4729.0 4744.4 5142.0 5396.6 5692.6 5837.6 5849.1 3780.1 3937.9 3968.6 3968.6 3984.4 5574.6 5824.8 5970.1 6118.7 6213.8 3738.4 4015.6 4016.0 4018.0 4032.2 3415.6 3708.9 3745.7 4092.6 4110.5 4170.8 4197.9 4747.1 4762.9 4774.2 4664.9 4998.4 5157.4 5939.4 5942.8 1299.4 1316.2 1449.3 1520.6 1556.5 949.5 961.1 1000.3 1000.9 1024.7 5126.9 5478.9 5752.2 5773.3 5808.9 5707.9 6167.6 6565.5 6572.1 6595.7 5532.9 5768.7 5783.9 5813.7 5888.3 2944.7 3393.7 3399.5 3449.5 3452.9 6040.2 6320.0 6791.2 6795.4 6813.7 4931.3 5048.8 5365.9 6222.9 6257.8 5349.5 5830.4 6086.5 6385.9 6393.6 4917.0 5590.2 5731.3 6097.0 6114.7 5314.9 6003.0 6018.6 6025.9 6054.8 4343.4 4630.3 5706.7 6171.3 6391.0 3527.7 3827.9 3959.2 3965.8 4010.6 4577.8 5924.8 6020.2 6080.8 6090.7 5187.2 5915.4 5928.7 5930.0 5974.5 5360.9 5848.5 5885.3 5909.6 5949.9 5086.2 5401.5 5714.7 5721.0 5774.9 5868.4 5898.9 5899.8 5910.7 5921.8 5277.3 5299.6 5544.3 5615.9 5800.5 13:51:24 INFO - PROCESS | 2874 | 13:51:24 INFO - PROCESS | 2874 | [#5] yui.html Cycles:180 Average:18589.06 Median:32893.98 stddev:26515.51 (80.6%) stddev-sans-first:26555.80 13:51:24 INFO - PROCESS | 2874 | Values: 681.0 681.6 695.5 704.8 758.2 14651.6 14864.9 14880.0 14900.8 15086.8 10819.5 13733.5 13763.2 13765.5 15079.6 767.9 882.9 915.1 926.4 933.9 839.9 902.6 902.7 911.3 930.1 968.9 972.2 973.5 974.3 976.4 949.8 961.9 965.1 969.9 971.7 701.6 716.6 717.8 723.1 723.7 14983.6 15034.8 15202.3 15282.5 15282.9 12077.4 12287.8 12302.6 12474.2 12508.1 10123.6 10134.5 10245.4 10351.6 10453.1 3430.6 3481.4 3490.4 3536.7 3543.6 79490.2 79885.0 82085.0 82320.0 83367.6 9110.3 9236.1 9260.7 9419.9 9434.6 21484.2 21508.1 21833.5 21845.5 21883.5 7270.1 7467.0 7472.3 7492.4 7554.0 4059.4 4063.9 4064.7 4138.0 4142.0 3550.2 3588.1 3621.8 3633.7 3636.3 641.6 654.7 657.8 664.3 675.4 560.2 591.9 595.5 599.8 610.5 8425.0 9051.8 9054.4 9068.6 9144.1 525.3 558.0 593.8 606.4 636.6 3879.1 4014.6 4069.8 4078.5 4218.4 90164.1 93650.5 94063.6 94273.1 99927.0 81030.0 82802.6 83500.6 84454.6 85867.6 76903.0 77387.8 77551.4 77688.0 78050.0 7623.0 8196.5 8306.5 8372.0 8507.9 47155.0 47211.5 47264.3 47389.5 47755.0 63533.7 65146.3 66015.7 66153.7 66222.7 10837.6 10967.5 11032.2 11151.1 11238.9 11981.9 11994.4 12152.5 12201.1 12529.0 11086.5 11342.7 11510.6 11664.9 11671.6 11364.4 11388.2 11432.9 11651.3 11954.2 8627.4 9623.4 10411.3 10790.8 11669.0 8370.0 9529.9 9691.3 9876.4 10025.5 9600.3 9700.5 9717.6 9746.4 9764.0 13:51:24 INFO - PROCESS | 2874 | -------- Summary: end -------- 13:51:24 INFO - PROCESS | 2874 | 13:51:25 INFO - TEST-INFO | 2874: exit 0 13:51:25 INFO - TEST-OK | dromaeo_css | took 1111753ms 13:51:25 INFO - TEST-START | kraken 13:51:25 INFO - operating with platform_type : mac_ 13:51:25 INFO - Initialising browser for kraken test... 13:51:25 INFO - TEST-INFO | started process 2878 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/tmpeDHZnM/profile http://localhost:61706/getInfo.html) 13:51:28 INFO - PROCESS | 2878 | __metrics Screen width/height:1600/1200 13:51:28 INFO - PROCESS | 2878 | colorDepth:24 13:51:28 INFO - PROCESS | 2878 | Browser inner width/height: 1024/689 13:51:28 INFO - PROCESS | 2878 | __metrics 13:51:28 ERROR - PROCESS | 2878 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 13:51:28 INFO - TEST-INFO | 2878: exit 0 13:51:28 INFO - Browser initialized. 13:51:28 INFO - Running cycle 1/1 for kraken test... 13:51:28 INFO - TEST-INFO | started process 2881 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/tmpeDHZnM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:51:40 INFO - PROCESS | 2881 | RSS: Main: 221687808 13:51:40 INFO - PROCESS | 2881 | 13:51:43 INFO - PROCESS | 2881 | Cycle 1(1): loaded http://localhost:61706/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:61706/tests/kraken/driver.html?testName=audio-beat-detection) 13:51:44 INFO - PROCESS | 2881 | RSS: Main: 233304064 13:51:44 INFO - PROCESS | 2881 | 13:51:47 INFO - PROCESS | 2881 | Cycle 1(1): loaded http://localhost:61706/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:61706/tests/kraken/driver.html?testName=audio-dft) 13:51:47 INFO - PROCESS | 2881 | RSS: Main: 242556928 13:51:47 INFO - PROCESS | 2881 | 13:51:51 INFO - PROCESS | 2881 | Cycle 1(1): loaded http://localhost:61706/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:61706/tests/kraken/driver.html?testName=audio-fft) 13:51:51 INFO - PROCESS | 2881 | RSS: Main: 244940800 13:51:51 INFO - PROCESS | 2881 | 13:51:53 INFO - PROCESS | 2881 | Cycle 1(1): loaded http://localhost:61706/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:61706/tests/kraken/driver.html?testName=audio-oscillator) 13:51:54 INFO - PROCESS | 2881 | RSS: Main: 245952512 13:51:54 INFO - PROCESS | 2881 | 13:51:56 INFO - PROCESS | 2881 | Cycle 1(1): loaded http://localhost:61706/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:61706/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:51:57 INFO - PROCESS | 2881 | RSS: Main: 246480896 13:51:57 INFO - PROCESS | 2881 | 13:52:00 INFO - PROCESS | 2881 | Cycle 1(1): loaded http://localhost:61706/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:61706/tests/kraken/driver.html?testName=imaging-darkroom) 13:52:00 INFO - PROCESS | 2881 | RSS: Main: 246480896 13:52:00 INFO - PROCESS | 2881 | 13:52:03 INFO - PROCESS | 2881 | Cycle 1(1): loaded http://localhost:61706/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:61706/tests/kraken/driver.html?testName=imaging-desaturate) 13:52:04 INFO - PROCESS | 2881 | RSS: Main: 247123968 13:52:04 INFO - PROCESS | 2881 | 13:52:07 INFO - PROCESS | 2881 | Cycle 1(1): loaded http://localhost:61706/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:61706/tests/kraken/driver.html?testName=json-parse-financial) 13:52:07 INFO - PROCESS | 2881 | RSS: Main: 246788096 13:52:07 INFO - PROCESS | 2881 | 13:52:09 INFO - PROCESS | 2881 | Cycle 1(1): loaded http://localhost:61706/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:61706/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:52:09 INFO - PROCESS | 2881 | RSS: Main: 246087680 13:52:09 INFO - PROCESS | 2881 | 13:52:11 INFO - PROCESS | 2881 | Cycle 1(1): loaded http://localhost:61706/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:61706/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:52:12 INFO - PROCESS | 2881 | RSS: Main: 247554048 13:52:12 INFO - PROCESS | 2881 | 13:52:14 INFO - PROCESS | 2881 | Cycle 1(1): loaded http://localhost:61706/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:61706/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:52:15 INFO - PROCESS | 2881 | RSS: Main: 247750656 13:52:15 INFO - PROCESS | 2881 | 13:52:18 INFO - PROCESS | 2881 | Cycle 1(1): loaded http://localhost:61706/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:61706/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:52:18 INFO - PROCESS | 2881 | RSS: Main: 247894016 13:52:18 INFO - PROCESS | 2881 | 13:52:21 INFO - PROCESS | 2881 | Cycle 1(1): loaded http://localhost:61706/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:61706/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:52:22 INFO - PROCESS | 2881 | RSS: Main: 247889920 13:52:22 INFO - PROCESS | 2881 | 13:52:24 INFO - PROCESS | 2881 | Cycle 1(1): loaded http://localhost:61706/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:61706/tests/kraken/driver.html?testName=ai-astar) 13:52:24 INFO - PROCESS | 2881 | RSS: Main: 249614336 13:52:24 INFO - PROCESS | 2881 | 13:52:24 INFO - PROCESS | 2881 | __start_tp_report 13:52:24 INFO - PROCESS | 2881 | _x_x_mozilla_page_load 13:52:24 INFO - PROCESS | 2881 | _x_x_mozilla_page_load_details 13:52:24 INFO - PROCESS | 2881 | |i|pagename|runs| 13:52:24 INFO - PROCESS | 2881 | |0;ai-astar;119;132;121;118;123;135;117;122;138;119 13:52:24 INFO - PROCESS | 2881 | |1;audio-beat-detection;159;108;169;173;156;148;109;147;175;151 13:52:24 INFO - PROCESS | 2881 | |2;audio-dft;263;257;216;275;199;189;199;198;199;204 13:52:24 INFO - PROCESS | 2881 | |3;audio-fft;66;80;79;149;59;57;56;57;129;56 13:52:24 INFO - PROCESS | 2881 | |4;audio-oscillator;79;84;122;75;82;84;123;79;88;122 13:52:24 INFO - PROCESS | 2881 | |5;imaging-gaussian-blur;136;86;92;119;89;89;88;79;134;119 13:52:24 INFO - PROCESS | 2881 | |6;imaging-darkroom;95;95;95;94;95;97;93;96;95;94 13:52:24 INFO - PROCESS | 2881 | |7;imaging-desaturate;90;91;92;91;94;89;89;94;91;91 13:52:24 INFO - PROCESS | 2881 | |8;json-parse-financial;60;66;66;67;65;64;67;81;64;67 13:52:24 INFO - PROCESS | 2881 | |9;json-stringify-tinderbox;66;69;72;68;70;75;74;67;68;71 13:52:24 INFO - PROCESS | 2881 | |10;stanford-crypto-aes;82;82;81;82;81;83;81;86;87;85 13:52:24 INFO - PROCESS | 2881 | |11;stanford-crypto-ccm;134;150;166;133;143;147;149;135;181;167 13:52:24 INFO - PROCESS | 2881 | |12;stanford-crypto-pbkdf2;167;172;169;170;171;170;169;169;181;226 13:52:24 INFO - PROCESS | 2881 | |13;stanford-crypto-sha256-iterative;70;70;70;69;66;71;67;80;98;97 13:52:24 INFO - PROCESS | 2881 | __end_tp_report 13:52:24 INFO - PROCESS | 2881 | __start_cc_report 13:52:24 INFO - PROCESS | 2881 | _x_x_mozilla_cycle_collect,1101 13:52:24 INFO - PROCESS | 2881 | __end_cc_report 13:52:24 INFO - PROCESS | 2881 | __startTimestamp1461617544300__endTimestamp 13:52:24 INFO - PROCESS | 2881 | 13:52:24 INFO - PROCESS | 2881 | ------- Summary: start ------- 13:52:24 INFO - PROCESS | 2881 | Number of tests: 14 13:52:24 INFO - PROCESS | 2881 | 13:52:24 INFO - PROCESS | 2881 | [#0] ai-astar Cycles:10 Average:124.40 Median:122.50 stddev:7.66 (6.3%) stddev-sans-first:7.87 13:52:24 INFO - PROCESS | 2881 | Values: 119.0 132.0 121.0 118.0 123.0 135.0 117.0 122.0 138.0 119.0 13:52:24 INFO - PROCESS | 2881 | 13:52:24 INFO - PROCESS | 2881 | [#1] audio-beat-detection Cycles:10 Average:149.50 Median:157.50 stddev:23.78 (15.1%) stddev-sans-first:24.97 13:52:24 INFO - PROCESS | 2881 | Values: 159.0 108.0 169.0 173.0 156.0 148.0 109.0 147.0 175.0 151.0 13:52:24 INFO - PROCESS | 2881 | 13:52:24 INFO - PROCESS | 2881 | [#2] audio-dft Cycles:10 Average:219.90 Median:210.00 stddev:32.12 (15.3%) stddev-sans-first:30.04 13:52:24 INFO - PROCESS | 2881 | Values: 263.0 257.0 216.0 275.0 199.0 189.0 199.0 198.0 199.0 204.0 13:52:24 INFO - PROCESS | 2881 | 13:52:24 INFO - PROCESS | 2881 | [#3] audio-fft Cycles:10 Average:78.80 Median:58.00 stddev:33.33 (57.5%) stddev-sans-first:35.02 13:52:24 INFO - PROCESS | 2881 | Values: 66.0 80.0 79.0 149.0 59.0 57.0 56.0 57.0 129.0 56.0 13:52:24 INFO - PROCESS | 2881 | 13:52:24 INFO - PROCESS | 2881 | [#4] audio-oscillator Cycles:10 Average:93.80 Median:80.50 stddev:20.00 (24.8%) stddev-sans-first:20.48 13:52:24 INFO - PROCESS | 2881 | Values: 79.0 84.0 122.0 75.0 82.0 84.0 123.0 79.0 88.0 122.0 13:52:24 INFO - PROCESS | 2881 | 13:52:24 INFO - PROCESS | 2881 | [#5] imaging-gaussian-blur Cycles:10 Average:103.10 Median:87.00 stddev:21.51 (24.7%) stddev-sans-first:19.24 13:52:24 INFO - PROCESS | 2881 | Values: 136.0 86.0 92.0 119.0 89.0 89.0 88.0 79.0 134.0 119.0 13:52:24 INFO - PROCESS | 2881 | 13:52:24 INFO - PROCESS | 2881 | [#6] imaging-darkroom Cycles:10 Average:94.90 Median:95.00 stddev:1.10 (1.2%) stddev-sans-first:1.17 13:52:24 INFO - PROCESS | 2881 | Values: 95.0 95.0 95.0 94.0 95.0 97.0 93.0 96.0 95.0 94.0 13:52:24 INFO - PROCESS | 2881 | 13:52:24 INFO - PROCESS | 2881 | [#7] imaging-desaturate Cycles:10 Average:91.20 Median:91.00 stddev:1.75 (1.9%) stddev-sans-first:1.80 13:52:24 INFO - PROCESS | 2881 | Values: 90.0 91.0 92.0 91.0 94.0 89.0 89.0 94.0 91.0 91.0 13:52:24 INFO - PROCESS | 2881 | 13:52:24 INFO - PROCESS | 2881 | [#8] json-parse-financial Cycles:10 Average:66.70 Median:66.50 stddev:5.46 (8.2%) stddev-sans-first:5.22 13:52:24 INFO - PROCESS | 2881 | Values: 60.0 66.0 66.0 67.0 65.0 64.0 67.0 81.0 64.0 67.0 13:52:24 INFO - PROCESS | 2881 | 13:52:24 INFO - PROCESS | 2881 | [#9] json-stringify-tinderbox Cycles:10 Average:70.00 Median:70.50 stddev:2.98 (4.2%) stddev-sans-first:2.79 13:52:24 INFO - PROCESS | 2881 | Values: 66.0 69.0 72.0 68.0 70.0 75.0 74.0 67.0 68.0 71.0 13:52:24 INFO - PROCESS | 2881 | 13:52:24 INFO - PROCESS | 2881 | [#10] stanford-crypto-aes Cycles:10 Average:83.00 Median:82.50 stddev:2.21 (2.7%) stddev-sans-first:2.32 13:52:24 INFO - PROCESS | 2881 | Values: 82.0 82.0 81.0 82.0 81.0 83.0 81.0 86.0 87.0 85.0 13:52:24 INFO - PROCESS | 2881 | 13:52:24 INFO - PROCESS | 2881 | [#11] stanford-crypto-ccm Cycles:10 Average:150.50 Median:149.50 stddev:16.10 (10.8%) stddev-sans-first:15.93 13:52:24 INFO - PROCESS | 2881 | Values: 134.0 150.0 166.0 133.0 143.0 147.0 149.0 135.0 181.0 167.0 13:52:24 INFO - PROCESS | 2881 | 13:52:24 INFO - PROCESS | 2881 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.40 Median:170.50 stddev:17.84 (10.5%) stddev-sans-first:18.60 13:52:24 INFO - PROCESS | 2881 | Values: 167.0 172.0 169.0 170.0 171.0 170.0 169.0 169.0 181.0 226.0 13:52:24 INFO - PROCESS | 2881 | 13:52:24 INFO - PROCESS | 2881 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.80 Median:70.50 stddev:12.04 (17.1%) stddev-sans-first:12.58 13:52:24 INFO - PROCESS | 2881 | Values: 70.0 70.0 70.0 69.0 66.0 71.0 67.0 80.0 98.0 97.0 13:52:24 INFO - PROCESS | 2881 | -------- Summary: end -------- 13:52:24 INFO - PROCESS | 2881 | 13:52:25 INFO - TEST-INFO | 2881: exit 0 13:52:25 INFO - TEST-OK | kraken | took 59810ms 13:52:25 INFO - SUITE-END | took 1171s 13:52:25 INFO - Completed test suite (00:19:31) 13:52:25 INFO - javascript benchmark 13:52:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3297.7032067113964, 3467.306538692261, 3478.808665523396, 3495.667911548402, 3538.310029544238, 5661.886762264752, 5668.121441176619, 5670.4896559309655, 5713.0, 5721.227634269378, 5694.487496125348, 5717.971410142944, 5751.597388182829, 5753.367129615739, 5767.9423205768035, 5172.301739265207, 5174.560162386183, 5191.325127733389, 5240.633155679104, 5240.9213861792105, 5145.202493613496, 5219.373675158986, 5233.0, 5328.227407025998, 5379.5696344292555, 4810.855674329776, 4922.950770492285, 5209.609279304067, 5226.7647955841885, 5240.44975277596, 4378.40891479651, 4849.757512124415, 5080.872978175538, 5203.219517072432, 5233.712145831978, 4976.701397916137, 5164.096283150434, 5168.8449346519665, 5179.896402071938, 5200.8179713710215, 5691.943080569183, 5722.685252311121, 5729.770809167629, 5748.310202775652, 5772.624779389326, 6095.451409373133, 6218.906716399258, 6230.376962303733, 6278.4663303619245, 6287.402696743802, 5032.974835125801, 6143.603994442171, 6146.66193359365, 6317.715702793385, 6325.240971083499, 4695.5304469552775, 5820.417958204188, 5827.388124246935, 5832.212651292064, 5839.445252700986, 6300.779472718433, 6308.684565771693, 6311.936880631227, 6340.651264180515, 6366.395192456709, 6091.9695401523595, 6109.4653969264045, 6123.28262147438, 6179.196704428396, 6188.8762224755255, 6304.432601065926, 6324.936750632435, 6328.968355158195, 6337.746490140343, 6347.904781428282, 6242.28213755424, 6265.843353916098, 6273.686315684197, 6291.937080629135, 6297.874042519215, 5073.594112470993, 6113.658745237732, 6263.467605253604, 6294.622322660655, 6312.558120931582, 4823.493533179036, 4904.0, 4929.285253638274, 4942.5304596063315, 4960.181570040902, 3789.621037896188, 3959.544652364957, 4062.3297155968935, 4344.1311737652595, 4461.174682683649, 5696.572757043238, 5737.741801618854, 5746.798862039892, 5749.396313387034, 5759.683217423082, 6241.34465881085, 6241.407066328691, 6258.249010118724, 6261.373862613793, 6276.309605943343, 4684.8360307389075, 5594.328680558359, 5685.971570142123, 5691.800786972436, 5717.142428635738, 5408.459154084639, 5425.891482170414, 5441.374241962146, 5461.726913654381, 5472.2338872557875, 6365.172527571293, 6379.393957574023, 6380.55336126476, 6380.808575742735, 6398.776042838478, 6178.9382106180165, 6235.345288744798, 6270.686465676789, 6344.841378965562, 6361.109444677842, 6314.273858506331, 6346.428821406095, 6363.681815909279, 6370.490360771035, 6375.840603984752, 4808.471068182427, 6161.938380616137, 6238.563300568917, 6261.874762504815, 6283.874322513616, 6129.328227221949, 6202.472789812917, 6279.058141278845, 6288.339724329063, 6319.842003949938, 6137.478314343152, 6146.631202127886, 6149.446549810538, 6150.815475535741, 6155.938440615716, 6112.694365281807, 6151.046587778901, 6169.475594574512, 6169.691515424301, 6173.59871608344, 4967.807726145771, 5762.135679648086, 5852.531797456279, 5967.791127310524, 6037.69811509414, 6124.0, 6131.816045518642, 6151.630902145886, 6167.907481387872, 6181.629102253879, 5824.475797178275, 6045.153678484928, 6073.0283154695035, 6093.360197179232, 6098.908516372347], "value": 5667.574610019319, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [479.9928001079982, 527.0302643136629, 528.8149147798271, 555.84714203594, 565.8755073883744, 38457.84626461207, 39429.0, 39479.0130246744, 39555.406668899916, 39687.60312396875, 41197.17605647885, 41683.58316416835, 42074.158516829724, 42167.734967950935, 42209.78895105528, 9485.905140948571, 9682.757931051745, 9716.805663886718, 9732.41605503672, 9740.659076932308, 9561.521923903812, 9742.318037737361, 9743.123118919295, 9750.609975601003, 9762.170215531689, 9686.854697179548, 9695.418274903528, 9712.028797120267, 9726.2705297103, 9758.658446954358, 9663.806723865519, 9733.56198971048, 9740.4642744649, 9748.561314740853, 9753.90246097537, 9552.614870843758, 9641.469719165443, 9673.951630241874, 9686.0, 9695.418274903528, 39372.803135984424, 39604.20791584152, 39738.006549836195, 39769.00577485557, 39791.00522486932, 2606.7002294736158, 2763.8203516771546, 3110.833437460952, 3215.115843143131, 3284.490903909874, 26521.734782652118, 27406.5889011665, 27612.447751044867, 27693.723062769517, 28535.0, 2703.297142742901, 2740.904068357598, 2764.2812868654096, 2772.1683494951635, 2788.512010398172, 377986.1100694479, 433817.0, 440041.0, 441471.0, 441493.0, 34483.82758086194, 35109.12227194289, 35166.82416587901, 35999.64000360015, 36258.09354766099, 35459.4681079784, 35864.924052278475, 35994.820025900226, 36087.09782255465, 36298.45552316718, 14292.714145717027, 15048.699026019638, 15601.453949111727, 15618.843811561967, 16176.352945882269, 13202.33988300581, 13203.735925281633, 13445.865541344461, 13583.796243056362, 13589.52436664712, 8218.465799722999, 8261.710840120568, 8292.875606865902, 8309.169083091643, 8337.291330236876, 3363.882264120744, 3401.982990085083, 3402.5066365376883, 3413.7951722896705, 3423.623401425841, 931.6040682709821, 973.6835528453281, 1005.6982905128432, 1027.0961553832728, 1154.8152295632658, 25863.0, 30773.69226307753, 30927.91752288704, 31076.0, 31185.376292474022, 1139.635316698665, 1173.524722487394, 1188.1326631559011, 1215.598852378713, 1230.2864338683542, 12863.614091577268, 15980.76028859568, 16037.11795851216, 16147.114168798747, 16183.514494565183, 575863.0, 662944.0, 669236.0, 672358.0, 680150.0, 54668.0, 61700.0, 62639.373606263354, 62873.05690414648, 63157.36842631515, 1307.6338625184962, 1476.7268055409784, 1499.9625009374636, 1501.4369611395728, 1501.9474318398804, 30187.396252074395, 30640.84679576588, 30749.692503074682, 30777.692223077484, 31190.532142018343, 42821.1435771289, 52049.21926171035, 52363.0, 52760.0, 53052.73473632607, 483375.0, 585989.0, 587882.0, 588482.0, 589086.0, 27371.31571710723, 27632.723672763015, 27833.0, 27947.301317467227, 28066.7369968356, 42773.57226427696, 44297.55702443063, 44424.77787611041, 44718.329225061, 44744.776276119715, 30094.548581771727, 38335.80832095933, 38370.2325953485, 39072.41391379188, 39101.217975640895, 36527.0, 36765.0, 36900.63099369079, 37019.62980370269, 37190.44214336733, 31014.914477993163, 35903.820480897426, 36466.45300320444, 37248.06879828026, 37295.0, 31045.844770776002, 32885.0, 33634.831825840716, 33792.0, 33977.83011084929, 25404.364890877874, 25686.486270274865, 25709.228723138334, 25982.0, 25995.610065849407], "value": 18351.93068143519, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.8336073725717, 399.7961039869667, 402.7643828360409, 406.1470911086718, 412.1715352142195, 20377.898110509446, 21062.89468552655, 21177.364679059632, 21213.681794773092, 21663.566728665417, 20430.387088387335, 20802.0, 21703.131874724993, 22252.0, 22435.663465048037, 5216.217567364906, 5834.795782147626, 6077.4226448487325, 6184.319724830265, 6186.288576813657, 6033.849153771147, 6059.151718759377, 6140.416660417253, 6145.692715364236, 6151.169592105053, 5916.437938395867, 5988.8203353899235, 6027.487663548603, 6040.154378387029, 6066.817995460143, 5784.1034639630925, 6029.035354343306, 6065.939340606604, 6146.078088286748, 6152.230971128609, 5883.676397798119, 5939.4357536034, 5949.167116603679, 5955.672438015908, 5968.731407086691, 24321.270361889172, 25156.587123231577, 25431.364215894566, 26009.21972340814, 26513.204603861723, 2144.9249276275254, 2337.0418128567376, 2351.1888398502488, 2522.3063657494154, 2611.3341098019882, 9928.50357482123, 10701.785964280749, 13931.930340348234, 14807.407703691839, 15794.183668878111, 1591.2759694339047, 1665.5752783040368, 1723.758673785671, 1724.6205834716343, 1756.7277072053853, 128200.3589982044, 129146.7085329144, 132084.33957830246, 136533.3173334127, 137972.0, 11877.22798018126, 12017.939910300567, 12046.759064818654, 12142.514299427923, 12209.145359824905, 15456.38174473031, 15496.147711875956, 15543.378264869505, 15548.22258887051, 15549.84450155484, 9150.176484116462, 9159.587818548182, 9201.447913125234, 9218.769530761785, 9339.813203735886, 7323.853522929511, 7493.288137626917, 8284.840122382871, 9035.819283614292, 9059.773505662411, 3880.553736320303, 4138.689598280081, 4703.318018887242, 4732.485604606492, 4736.076465089274, 1704.343827626348, 1957.2366776956999, 1989.44295597233, 2012.3962811156887, 2022.1506967073865, 891.8439273127177, 936.1200471556715, 965.8647789309503, 1002.8746406699163, 1004.1514919892679, 17847.863094704942, 21263.93680315978, 21363.0, 21391.46521336948, 21716.89141554282, 826.7023871406288, 1003.3979612232602, 1006.3609607899036, 1024.71820249432, 1029.7940411917646, 10845.403502807263, 11224.214304998572, 11282.266652667551, 11318.773624527463, 11346.886531134583, 613856.0, 616495.0, 620426.0, 627851.0, 631970.0, 76269.47461050747, 87860.0, 102589.0, 143062.0, 150951.0, 996.1980605612407, 998.670438755209, 1012.3015119567475, 1023.9606799098921, 1028.1466382902322, 18523.90738046301, 18615.81384186195, 18746.250149993848, 18748.156332964776, 19001.334953276568, 50130.74934625303, 52909.0, 55811.0, 56158.43841561532, 56688.43311566996, 78529.2147078522, 79955.0, 79963.0, 80096.19903801121, 82047.0, 7437.8140546486775, 8356.080831108451, 8411.621477033426, 8531.658733650584, 8898.332625053017, 7687.308142267223, 9591.952040239754, 11526.59656912004, 12937.870621294043, 13127.934360328138, 11348.0, 11467.770644586895, 11503.654890353302, 11646.475908584303, 11681.88318116808, 11221.158413118885, 11254.0, 11351.716207094558, 11474.311541307548, 11655.59205427806, 6131.938680613315, 8535.53054582004, 11563.36401497892, 11753.353565553976, 11841.407929603312, 8315.251627353568, 8450.957745211235, 8555.486670799772, 8589.14108589136, 8702.869456958024, 8407.747767566983, 10263.127634150882, 11072.446377681244, 11195.2723073, 11445.599404020819], "value": 9755.487436562591, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1603.5991002249439, 1630.7798447209627, 1866.5613580808508, 1930.4208737378792, 1955.3840540229817, 8129.349652027839, 8646.308295336374, 8771.956140219298, 9137.131972462605, 9162.816743665131, 7385.556866588008, 8753.255973242281, 8774.254188393978, 8848.823023539526, 8853.026167121612, 2443.5601591713485, 2444.8288619796667, 2450.4363996280867, 2477.8513289202615, 2505.912293069743, 2084.853330668134, 2244.5623103494827, 2302.1942320187886, 2313.467902382453, 2336.8247381446376, 2442.3894026493376, 2460.4218008767925, 2470.9258722238274, 2487.340854673513, 2495.1641200197864, 2451.2156110044793, 2459.0655550890724, 2461.9507609847883, 2468.691413573303, 2471.233917485585, 2445.437549363656, 2459.0, 2464.6056630938965, 2473.1591258972035, 2473.282748003077, 8870.37907346486, 8975.640974361018, 8992.190702836777, 9004.144606262395, 9028.683996060172, 5303.761330740126, 5482.753276102585, 5558.833235002957, 5597.44025597441, 5625.8593535161535, 3647.960331305565, 3648.3797754381817, 3653.561572611277, 3734.5518537775642, 3737.9065523362133, 1044.6134930075846, 1049.7323182588468, 1057.9947100264526, 1059.8304271316629, 1079.0827796373098, 11725.589604363806, 11835.644930652094, 11914.523419063315, 11972.281663100242, 11989.400529973467, 4256.744595324229, 4794.0, 4867.318575399447, 4954.455009948902, 4954.950450495449, 10015.69952901414, 10107.343022703648, 10117.190624750003, 10120.240981926385, 10125.291229614004, 5414.10667239901, 5443.8094666686475, 5495.9832431000395, 5569.053260945649, 5636.633618814764, 4438.800253988578, 4834.95165048352, 5009.849704508871, 5012.373453318335, 5070.29015937769, 4022.6379625833815, 4033.153037862082, 4118.608732170439, 4172.019575399779, 4179.477565304337, 1176.7937863689615, 1337.7190789934027, 1359.5581436033328, 1360.7550640884735, 1371.4994027180007, 880.3309484791605, 932.3731281714819, 942.6842007927421, 951.4386511957978, 984.2568860510269, 5194.597458686134, 5629.761452480448, 5843.29880414353, 5928.851778705481, 6026.096085587197, 430.3716573802284, 434.46343765449643, 459.0978726801784, 461.1767994130461, 465.4205514134924, 4002.5597184310286, 4033.173199494155, 4050.675945924319, 4055.8986025349013, 4089.325261331847, 9987.650432234837, 10433.373997560018, 10507.00183482568, 10944.507497162647, 11007.339559626447, 10724.946375268073, 10812.945935270272, 10820.4048777318, 10888.29226100301, 10993.835092473619, 7909.932159158555, 8091.757247282591, 8184.140665230184, 8192.057913340042, 8265.008199016036, 5867.823965281048, 5867.9413205869105, 5893.499052580407, 5953.821385358447, 5972.641641501524, 9890.109890109925, 9903.50482475859, 10042.748931276776, 10086.293959423063, 10086.8486972694, 9864.950675246579, 10250.38497690141, 10262.025107614765, 10322.329048611817, 10483.842742358718, 6874.0, 7321.816954576178, 7490.213527579525, 7579.886301705368, 7646.617669116411, 8449.66201351939, 8465.873011904703, 8537.231649151357, 8568.871466927876, 8571.0, 7321.707131714672, 7510.737124200627, 7554.320111190019, 7564.205758395289, 7639.121501027453, 6524.0, 7175.712971481083, 7248.528845625017, 7289.161746399232, 7357.374623157091, 6945.409640180439, 7000.579965202104, 7032.1912980007955, 7038.472114591528, 7061.6822243000215, 4472.127935052634, 4779.235322348408, 4928.728919909439, 4997.300377947017, 5175.094358487175, 4942.555170034714, 5018.5232402921665, 5033.0, 5057.468965758542, 5206.97396513015], "value": 4571.80516295459, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [286.22147758097975, 306.7699225580814, 318.29338867713676, 318.56515855856765, 319.89763275751767, 4141.813618387172, 4822.348982887309, 5431.049566325892, 5459.290292262011, 5515.972420137899, 5174.0, 5286.762095705692, 5533.806316778913, 5544.750486228119, 5558.166275058743, 4237.978810105953, 4243.7056697707185, 4245.214635292481, 4252.681048921328, 4267.743935363873, 3913.6477717005464, 4210.705165684709, 4233.470816147981, 4269.8078586463525, 4272.46594175728, 4077.979610101945, 4112.97943510282, 4128.401381799637, 4130.483689538808, 4168.956661637007, 3587.551556055493, 3634.854605815764, 4114.732542384751, 4137.77242251676, 4172.853950111731, 3622.836972336251, 3624.3476174288617, 3627.365211088049, 3650.2881938022097, 3665.651763082503, 4397.988462653576, 4909.484504127051, 5001.624878134155, 5118.309028281209, 5255.74003178633, 4175.498940127204, 4693.855117071536, 4724.67709041469, 4729.03744945847, 4744.4069491313585, 5141.974290128563, 5396.64921780083, 5692.601517893749, 5837.562182836262, 5849.081115652523, 3780.092777733352, 3937.9015524611827, 3968.5634580196156, 3968.583298753647, 3984.4222587724907, 5574.60977731559, 5824.796132135397, 5970.0746384310505, 6118.694065296718, 6213.782517611907, 3738.364478038686, 4015.638592526687, 4015.975926138816, 4017.995501124719, 4032.213718324957, 3415.64135765746, 3708.851645934132, 3745.6628903398823, 4092.5907409258834, 4110.506739191256, 4170.812313445842, 4197.853075142355, 4747.098051370229, 4762.92834112322, 4774.1883879739835, 4664.883377915579, 4998.425181104147, 5157.406898206679, 5939.406059394026, 5942.7920022799, 1299.402274953532, 1316.2497376495305, 1449.326063380533, 1520.6426489774894, 1556.4630202580092, 949.4730424614268, 961.1205746741792, 1000.2748007694508, 1000.8740167311774, 1024.7284469615558, 5126.92309615356, 5478.945210547843, 5752.194692743019, 5773.278340207474, 5808.850725231607, 5707.85955266775, 6167.64311851392, 6565.5227573796465, 6572.14562106923, 6595.670216489157, 5532.917006244909, 5768.711564421846, 5783.8554036149435, 5813.715250883238, 5888.293404791367, 2944.7349738523635, 3393.7285017198565, 3399.473081672345, 3449.4998225257195, 3452.861885524551, 6040.244969378828, 6319.96840015797, 6791.219009813836, 6795.38841504267, 6813.727450902007, 4931.312434723013, 5048.823291184791, 5365.892682146336, 6222.937770622236, 6257.811015906963, 5349.528879558153, 5830.416958304136, 6086.543509236825, 6385.850546901603, 6393.6483493408305, 4917.0, 5590.217369568345, 5731.283589551306, 6096.969515152573, 6114.676422959247, 5314.867128321823, 6003.0, 6018.638881667114, 6025.8852112359355, 6054.757809687563, 4343.392944610514, 4630.259158534618, 5706.686132262599, 6171.290301615371, 6391.01878417692, 3527.7354198434705, 3827.8851634449898, 3959.247742928834, 3965.841366345314, 4010.57888921659, 4577.793999269974, 5924.822255332347, 6020.157177994996, 6080.847978800389, 6090.69546522681, 5187.170052791535, 5915.37888521699, 5928.67392293428, 5929.99190137669, 5974.520573961757, 5360.892782144258, 5848.502877255481, 5885.323187833454, 5909.645421274738, 5949.91075133882, 5086.237064440364, 5401.459854014567, 5714.742836572281, 5720.971395142998, 5774.942250577608, 5868.354481007171, 5898.94101058984, 5899.793507227227, 5910.674912879833, 5921.792737254175, 5277.261183434399, 5299.5760339172, 5544.306961629796, 5615.894039735099, 5800.477956983892], "value": 4269.880069050946, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [680.9795706128816, 681.6251061915945, 695.4922906278416, 704.84845758162, 758.2455456820464, 14651.608097230772, 14864.925675371622, 14879.958402911787, 14900.776488352683, 15086.773698394532, 10819.513121909513, 13733.45066197351, 13763.243021633805, 13765.518206862762, 15079.62300942477, 767.9155292917775, 882.9249513791335, 915.0757734687963, 926.3561824531953, 933.8785957825507, 839.9160083991613, 902.5667679513838, 902.715644571961, 911.3165126155384, 930.1303281431849, 968.903109689029, 972.2270794718181, 973.459729849933, 974.2790335152007, 976.448306706712, 949.8100379923974, 961.9038096190395, 965.1217392173124, 969.9078587534173, 971.6793458158791, 701.5650296815955, 716.6166101135867, 717.7631381644045, 723.0889079759488, 723.6960476599841, 14983.625409364744, 15034.774478382833, 15202.315895784606, 15282.465113720966, 15282.923585382003, 12077.396130193456, 12287.815682764765, 12302.569410070604, 12474.18917770351, 12508.061895357794, 10123.595056197742, 10134.493275336205, 10245.436500992442, 10351.637692680795, 10453.059224669816, 3430.5711786026745, 3481.3559491494157, 3490.4240800267926, 3536.681698647134, 3543.6279190684863, 79490.20509794886, 79885.0, 82085.0, 82320.0, 83367.5831620838, 9110.27117830572, 9236.122568355995, 9260.675876344296, 9419.858702119474, 9434.575444105032, 21484.24805131813, 21508.139674412847, 21833.454163646034, 21845.453863653536, 21883.452913676967, 7270.091238595176, 7466.962665186639, 7472.29013243741, 7492.438067144986, 7553.962230188924, 4059.4316795648633, 4063.918721625551, 4064.6545043671617, 4137.958620413818, 4142.047329114261, 3550.218951830593, 3588.102974256436, 3621.8008009559726, 3633.65480279373, 3636.3454578176184, 641.6182371488957, 654.7348323928817, 657.7796438193259, 664.2659860853784, 675.3955210086945, 560.1849309255024, 591.9200907877467, 595.487880422836, 599.7870755881686, 610.5146408605169, 8424.957875210708, 9051.773705657279, 9054.366194366332, 9068.637254509878, 9144.131307525775, 525.3485677759551, 557.9916301255485, 593.8307582339099, 606.3572613030202, 636.6402982314977, 3879.1465877507467, 4014.578469260686, 4069.8168582413864, 4078.469798926121, 4218.374649880902, 90164.09835901688, 93650.53174734082, 94063.58904616436, 94273.0572694278, 99927.0, 81030.0, 82802.58598706967, 83500.58249708713, 84454.57772711097, 85867.57066214629, 76903.0, 77387.83918241231, 77551.44897102026, 77688.0, 78050.0, 7622.961885190539, 8196.508209507449, 8306.501609903426, 8371.95814020926, 8507.914920850711, 47155.0, 47211.52788472209, 47264.34574789866, 47389.526104738514, 47755.0, 63533.68233158805, 65146.34853651592, 66015.67968640696, 66153.66923165353, 66222.67554648976, 10837.645294338208, 10967.50646220938, 11032.172587055842, 11151.052160566443, 11238.943805280922, 11981.880181198076, 11994.400279986141, 12152.453139608915, 12201.14591978553, 12529.0, 11086.501107450345, 11342.716432089264, 11510.59712910044, 11664.94167529157, 11671.649850504498, 11364.374959377314, 11388.202825802115, 11432.885671143182, 11651.30092194471, 11954.163208575315, 8627.396082274432, 9623.374480658735, 10411.271211015157, 10790.784184316426, 11669.0, 8369.95815020921, 9529.857052144362, 9691.321607487691, 9876.40741555509, 10025.548850301608, 9600.279979001743, 9700.466474343979, 9717.56270967794, 9746.366486178376, 9763.974782648003], "value": 6943.049788750177, "unit": "score"}], "name": "dromaeo_css", "value": 7184.620556362951, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 132.0, 121.0, 118.0, 123.0, 135.0, 117.0, 122.0, 138.0, 119.0], "value": 124.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 108.0, 169.0, 173.0, 156.0, 148.0, 109.0, 147.0, 175.0, 151.0], "value": 149.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [263.0, 257.0, 216.0, 275.0, 199.0, 189.0, 199.0, 198.0, 199.0, 204.0], "value": 219.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 80.0, 79.0, 149.0, 59.0, 57.0, 56.0, 57.0, 129.0, 56.0], "value": 78.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 84.0, 122.0, 75.0, 82.0, 84.0, 123.0, 79.0, 88.0, 122.0], "value": 93.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 86.0, 92.0, 119.0, 89.0, 89.0, 88.0, 79.0, 134.0, 119.0], "value": 103.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 94.0, 95.0, 97.0, 93.0, 96.0, 95.0, 94.0], "value": 94.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 91.0, 92.0, 91.0, 94.0, 89.0, 89.0, 94.0, 91.0, 91.0], "value": 91.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 66.0, 66.0, 67.0, 65.0, 64.0, 67.0, 81.0, 64.0, 67.0], "value": 66.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 72.0, 68.0, 70.0, 75.0, 74.0, 67.0, 68.0, 71.0], "value": 70.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 81.0, 82.0, 81.0, 83.0, 81.0, 86.0, 87.0, 85.0], "value": 83.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 150.0, 166.0, 133.0, 143.0, 147.0, 149.0, 135.0, 181.0, 167.0], "value": 150.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 172.0, 169.0, 170.0, 171.0, 170.0, 169.0, 169.0, 181.0, 226.0], "value": 176.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 70.0, 69.0, 66.0, 71.0, 67.0, 80.0, 98.0, 97.0], "value": 75.8, "unit": "score"}], "name": "kraken", "value": 1578.0, "alertThreshold": 2.0}]} 13:52:25 INFO - Return code: 0 13:52:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 13:52:25 ERROR - # TBPL SUCCESS # 13:52:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:52:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:52:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:52:25 INFO - 2016-04-25 13:52:25.563 screenresolution[2886:132830] starting screenresolution argv=screenresolution get 13:52:25 INFO - 2016-04-25 13:52:25.566 screenresolution[2886:132830] Display 0: 1600x1200x32@60 13:52:25 INFO - 2016-04-25 13:52:25.572 screenresolution[2887:132833] starting screenresolution argv=screenresolution list 13:52:25 INFO - Available Modes on Display 0 13:52:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:52:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:52:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:52:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:52:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:52:25 INFO - Intel Iris: 13:52:25 INFO - Chipset Model: Intel Iris 13:52:25 INFO - Type: GPU 13:52:25 INFO - Bus: Built-In 13:52:25 INFO - VRAM (Dynamic, Max): 1536 MB 13:52:25 INFO - Vendor: Intel (0x8086) 13:52:25 INFO - Device ID: 0x0a2e 13:52:25 INFO - Revision ID: 0x0009 13:52:25 INFO - Displays: 13:52:25 INFO - Display: 13:52:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:52:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:52:25 INFO - Display Serial Number: 1600x1200 60 13:52:25 INFO - Main Display: Yes 13:52:25 INFO - Mirror: Off 13:52:25 INFO - Online: Yes 13:52:25 INFO - Rotation: Supported 13:52:25 INFO - Return code: 0 13:52:25 INFO - Running post-action listener: _resource_record_post_action 13:52:25 INFO - Running post-run listener: _resource_record_post_run 13:52:26 INFO - Total resource usage - Wall time: 1205s; CPU: 54.0%; Read bytes: 102400; Write bytes: 649461760; Read time: 1; Write time: 1867 13:52:26 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 350798336; Write bytes: 346301952; Read time: 28019; Write time: 635 13:52:26 INFO - run-tests - Wall time: 1174s; CPU: 54.0%; Read bytes: 102400; Write bytes: 292370944; Read time: 1; Write time: 1196 13:52:26 INFO - Running post-run listener: _upload_blobber_files 13:52:26 INFO - Blob upload gear active. 13:52:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:52:26 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 13:52:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:52:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:52:27 INFO - (blobuploader) - INFO - Open directory for files ... 13:52:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 13:52:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:52:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:52:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 13:52:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:52:28 INFO - (blobuploader) - INFO - Done attempting. 13:52:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 13:52:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:52:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:52:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 13:52:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:52:29 INFO - (blobuploader) - INFO - Done attempting. 13:52:29 INFO - (blobuploader) - INFO - Iteration through files over. 13:52:29 INFO - Return code: 0 13:52:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:52:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:52:29 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7d2ba05ef3181f489bf280bc58ed5b69f1a1bdff46384eb55805c91c784ad34d559caa4cbc9f16339766fc24f9bc9a9d8818f71f2e133d3902d588580e7ab405", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ddb54a16e9d9e66793e78f5a6d78e41f4f98468a380e0809fec13ee1357093c2ce0968efe13bc735fb379ca2f0c76abb46aa5b9b58bbf68659565bd837a6c6bd"} 13:52:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:52:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:52:29 INFO - Contents: 13:52:29 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7d2ba05ef3181f489bf280bc58ed5b69f1a1bdff46384eb55805c91c784ad34d559caa4cbc9f16339766fc24f9bc9a9d8818f71f2e133d3902d588580e7ab405", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ddb54a16e9d9e66793e78f5a6d78e41f4f98468a380e0809fec13ee1357093c2ce0968efe13bc735fb379ca2f0c76abb46aa5b9b58bbf68659565bd837a6c6bd"} 13:52:29 INFO - Running post-run listener: copy_logs_to_upload_dir 13:52:29 INFO - Copying logs to upload dir... 13:52:29 INFO - mkdir: /builds/slave/test/build/upload/logs 13:52:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.680296 ========= master_lag: 6.32 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-04-25 13:52:35.809819) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 3 secs) (at 2016-04-25 13:52:35.810843) ========= 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.ITvbMDdJgX/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.f2s9Yv6Clz/Listeners TMPDIR=/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7d2ba05ef3181f489bf280bc58ed5b69f1a1bdff46384eb55805c91c784ad34d559caa4cbc9f16339766fc24f9bc9a9d8818f71f2e133d3902d588580e7ab405", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ddb54a16e9d9e66793e78f5a6d78e41f4f98468a380e0809fec13ee1357093c2ce0968efe13bc735fb379ca2f0c76abb46aa5b9b58bbf68659565bd837a6c6bd"} build_url:https://queue.taskcluster.net/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011643 build_url: 'https://queue.taskcluster.net/v1/task/MTnAsegCRLKbQhmwYdMOFQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7d2ba05ef3181f489bf280bc58ed5b69f1a1bdff46384eb55805c91c784ad34d559caa4cbc9f16339766fc24f9bc9a9d8818f71f2e133d3902d588580e7ab405", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ddb54a16e9d9e66793e78f5a6d78e41f4f98468a380e0809fec13ee1357093c2ce0968efe13bc735fb379ca2f0c76abb46aa5b9b58bbf68659565bd837a6c6bd"}' ========= master_lag: 3.79 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 3 secs) (at 2016-04-25 13:52:39.616102) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 9 secs) (at 2016-04-25 13:52:39.616576) ========= 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.ITvbMDdJgX/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.f2s9Yv6Clz/Listeners TMPDIR=/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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.005277 ========= master_lag: 9.16 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 9 secs) (at 2016-04-25 13:52:48.782369) ========= ========= Started reboot skipped (results: 3, elapsed: 2 secs) (at 2016-04-25 13:52:48.782675) ========= ========= Finished reboot skipped (results: 3, elapsed: 2 secs) (at 2016-04-25 13:52:50.998016) ========= ========= Total master_lag: 19.34 =========