builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0244 starttime: 1464289727.73 results: success (0) buildid: 20160526100254 builduid: 1e85406e622c42658dbcf3a29055aacc revision: 3a217924ee0636422a9b14e96f774d9cf7404ac9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-26 12:08:47.731757) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-26 12:08:47.732159) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-26 12:08:47.732429) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-26 12:08:47.753083) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 12:08:47.753481) ========= 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.TihuaNKSQ9/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.1ZKS69xVfE/Listeners TMPDIR=/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-26 12:08:47-- 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% 14.4M=0.001s 2016-05-26 12:08:47 (14.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.114473 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 12:08:47.891614) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 12:08:47.892368) ========= 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.TihuaNKSQ9/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.1ZKS69xVfE/Listeners TMPDIR=/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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.020057 ========= master_lag: 0.26 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 12:08:48.168896) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-26 12:08:48.169280) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3a217924ee0636422a9b14e96f774d9cf7404ac9 --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 3a217924ee0636422a9b14e96f774d9cf7404ac9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TihuaNKSQ9/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.1ZKS69xVfE/Listeners TMPDIR=/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-26 12:08:48,214 truncating revision to first 12 chars 2016-05-26 12:08:48,214 Setting DEBUG logging. 2016-05-26 12:08:48,214 attempt 1/10 2016-05-26 12:08:48,214 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/3a217924ee06?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-26 12:08:49,959 unpacking tar archive at: mozilla-aurora-3a217924ee06/testing/mozharness/ program finished with exit code 0 elapsedTime=2.012584 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-26 12:08:50.205693) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-26 12:08:50.207029) ========= script_repo_revision: 3a217924ee0636422a9b14e96f774d9cf7404ac9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-26 12:08:50.207474) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-26 12:08:50.207819) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-26 12:08:50.224069) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-05-26 12:08:50.224801) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TihuaNKSQ9/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.1ZKS69xVfE/Listeners TMPDIR=/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 12:08:50 INFO - MultiFileLogger online at 20160526 12:08:50 in /builds/slave/test 12:08:50 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 12:08:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:08:50 INFO - {'append_to_log': False, 12:08:50 INFO - 'base_work_dir': '/builds/slave/test', 12:08:50 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 12:08:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:08:50 INFO - 'branch': 'Mozilla-Aurora', 12:08:50 INFO - 'buildbot_json_path': 'buildprops.json', 12:08:50 INFO - 'config_files': ('talos/mac_config.py',), 12:08:50 INFO - 'default_actions': ('clobber', 12:08:50 INFO - 'read-buildbot-config', 12:08:50 INFO - 'download-and-extract', 12:08:50 INFO - 'populate-webroot', 12:08:50 INFO - 'create-virtualenv', 12:08:50 INFO - 'install', 12:08:50 INFO - 'run-tests'), 12:08:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:08:50 INFO - 'download_minidump_stackwalk': True, 12:08:50 INFO - 'download_symbols': 'ondemand', 12:08:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:08:50 INFO - 'tooltool.py': '/tools/tooltool.py', 12:08:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:08:50 INFO - '/tools/misc-python/virtualenv.py')}, 12:08:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:08:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:08:50 INFO - 'installer_path': 'installer.exe', 12:08:50 INFO - 'log_level': 'info', 12:08:50 INFO - 'log_name': 'talos', 12:08:50 INFO - 'log_to_console': True, 12:08:50 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:08:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:08:50 INFO - 'opt_config_files': (), 12:08:50 INFO - 'pip_index': False, 12:08:50 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:08:50 INFO - 'cmd': ('bash', 12:08:50 INFO - '-c', 12:08:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:08:50 INFO - 'enabled': True, 12:08:50 INFO - 'halt_on_failure': False, 12:08:50 INFO - 'name': 'check_screen_resolution'},), 12:08:50 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:08:50 INFO - 'cmd': ('bash', 12:08:50 INFO - '-c', 12:08:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:08:50 INFO - 'enabled': True, 12:08:50 INFO - 'halt_on_failure': False, 12:08:50 INFO - 'name': 'check_screen_resolution'},), 12:08:50 INFO - 'run_cmd_checks_enabled': True, 12:08:50 INFO - 'sps_profile': False, 12:08:50 INFO - 'sps_profile_interval': 0, 12:08:50 INFO - 'suite': 'dromaeojs', 12:08:50 INFO - 'system_bits': '32', 12:08:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:08:50 INFO - 'title': 't-yosemite-r7-0244', 12:08:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:08:50 INFO - 'use_talos_json': True, 12:08:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:08:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:08:50 INFO - 'work_dir': 'build'} 12:08:50 INFO - ##### 12:08:50 INFO - ##### Running clobber step. 12:08:50 INFO - ##### 12:08:50 INFO - Running pre-action listener: _resource_record_pre_action 12:08:50 INFO - Running main action method: clobber 12:08:50 INFO - rmtree: /builds/slave/test/build 12:08:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:08:51 INFO - Running post-action listener: _resource_record_post_action 12:08:51 INFO - ##### 12:08:51 INFO - ##### Running read-buildbot-config step. 12:08:51 INFO - ##### 12:08:51 INFO - Running pre-action listener: _resource_record_pre_action 12:08:51 INFO - Running main action method: read_buildbot_config 12:08:51 INFO - Using buildbot properties: 12:08:51 INFO - { 12:08:51 INFO - "project": "", 12:08:51 INFO - "product": "firefox", 12:08:51 INFO - "script_repo_revision": "production", 12:08:51 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 12:08:51 INFO - "repository": "", 12:08:51 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 12:08:51 INFO - "buildid": "20160526100254", 12:08:51 INFO - "pgo_build": "False", 12:08:51 INFO - "basedir": "/builds/slave/test", 12:08:51 INFO - "buildnumber": 9, 12:08:51 INFO - "slavename": "t-yosemite-r7-0244", 12:08:51 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 12:08:51 INFO - "platform": "yosemite_r7", 12:08:51 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 12:08:51 INFO - "branch": "mozilla-aurora", 12:08:51 INFO - "repo_path": "releases/mozilla-aurora", 12:08:51 INFO - "revision": "3a217924ee0636422a9b14e96f774d9cf7404ac9", 12:08:51 INFO - "stage_platform": "macosx64", 12:08:51 INFO - "builduid": "1e85406e622c42658dbcf3a29055aacc", 12:08:51 INFO - "slavebuilddir": "test" 12:08:51 INFO - } 12:08:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/Tt_p062KSx-6sp0njp_UEA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 12:08:51 INFO - Running post-action listener: _resource_record_post_action 12:08:51 INFO - ##### 12:08:51 INFO - ##### Running download-and-extract step. 12:08:51 INFO - ##### 12:08:51 INFO - Running pre-action listener: _resource_record_pre_action 12:08:51 INFO - Running main action method: download_and_extract 12:08:51 INFO - mkdir: /builds/slave/test/build/tests 12:08:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:08:51 INFO - https://queue.taskcluster.net/v1/task/Tt_p062KSx-6sp0njp_UEA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 12:08:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tt_p062KSx-6sp0njp_UEA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 12:08:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tt_p062KSx-6sp0njp_UEA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 12:08:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tt_p062KSx-6sp0njp_UEA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 12:08:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tt_p062KSx-6sp0njp_UEA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 12:08:53 INFO - Downloaded 1199 bytes. 12:08:53 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 12:08:53 INFO - Using the following test package requirements: 12:08:53 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 12:08:53 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:08:53 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 12:08:53 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:08:53 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 12:08:53 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:08:53 INFO - u'jsshell-mac.zip'], 12:08:53 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:08:53 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 12:08:53 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 12:08:53 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:08:53 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 12:08:53 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:08:53 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 12:08:53 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:08:53 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 12:08:53 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:08:53 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 12:08:53 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 12:08:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:08:53 INFO - https://queue.taskcluster.net/v1/task/Tt_p062KSx-6sp0njp_UEA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:08:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tt_p062KSx-6sp0njp_UEA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 12:08:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tt_p062KSx-6sp0njp_UEA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 12:08:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tt_p062KSx-6sp0njp_UEA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 12:08:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tt_p062KSx-6sp0njp_UEA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 12:08:54 INFO - Downloaded 18425604 bytes. 12:08:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:08:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 12:08:57 INFO - Return code: 0 12:08:57 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 12:08:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:08:57 INFO - https://queue.taskcluster.net/v1/task/Tt_p062KSx-6sp0njp_UEA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:08:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tt_p062KSx-6sp0njp_UEA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 12:08:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tt_p062KSx-6sp0njp_UEA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 12:08:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tt_p062KSx-6sp0njp_UEA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 12:08:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tt_p062KSx-6sp0njp_UEA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 12:08:59 INFO - Downloaded 18425604 bytes. 12:08:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:08:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 12:09:02 INFO - Return code: 0 12:09:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:09:02 INFO - https://queue.taskcluster.net/v1/task/Tt_p062KSx-6sp0njp_UEA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 12:09:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tt_p062KSx-6sp0njp_UEA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 12:09:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tt_p062KSx-6sp0njp_UEA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 12:09:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tt_p062KSx-6sp0njp_UEA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 12:09:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tt_p062KSx-6sp0njp_UEA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 12:09:03 INFO - Downloaded 10840145 bytes. 12:09:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:09:03 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 12:09:04 INFO - Return code: 0 12:09:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:09:04 INFO - https://queue.taskcluster.net/v1/task/Tt_p062KSx-6sp0njp_UEA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 12:09:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tt_p062KSx-6sp0njp_UEA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 12:09:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tt_p062KSx-6sp0njp_UEA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 12:09:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tt_p062KSx-6sp0njp_UEA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 12:09:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Tt_p062KSx-6sp0njp_UEA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 12:09:10 INFO - Downloaded 114142009 bytes. 12:09:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Tt_p062KSx-6sp0njp_UEA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 12:09:10 INFO - mkdir: /builds/slave/test/properties 12:09:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:09:10 INFO - Writing to file /builds/slave/test/properties/build_url 12:09:10 INFO - Contents: 12:09:10 INFO - build_url:https://queue.taskcluster.net/v1/task/Tt_p062KSx-6sp0njp_UEA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 12:09:12 INFO - Running post-action listener: _resource_record_post_action 12:09:12 INFO - Running post-action listener: set_extra_try_arguments 12:09:12 INFO - ##### 12:09:12 INFO - ##### Running populate-webroot step. 12:09:12 INFO - ##### 12:09:12 INFO - Running pre-action listener: _resource_record_pre_action 12:09:12 INFO - Running main action method: populate_webroot 12:09:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:09:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:09:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:09:12 INFO - '--tppagecycles', 12:09:12 INFO - '7'], 12:09:12 INFO - 'tests': ['tsvgm']}}, 12:09:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:09:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:09:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:09:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:09:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:09:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:09:12 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:09:12 INFO - 'talos_options': ['--disable-e10s'], 12:09:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:09:12 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:09:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:09:12 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:09:12 INFO - 'talos_options': ['--disable-e10s'], 12:09:12 INFO - 'tests': ['damp', 'tps']}, 12:09:12 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:09:12 INFO - 'tests': ['damp', 'tps']}, 12:09:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:09:12 INFO - 'tests': ['dromaeo_dom']}, 12:09:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:09:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:09:12 INFO - 'tests': ['a11yr', 12:09:12 INFO - 'ts_paint', 12:09:12 INFO - 'tpaint', 12:09:12 INFO - 'sessionrestore', 12:09:12 INFO - 'sessionrestore_no_auto_restore', 12:09:12 INFO - 'tabpaint']}, 12:09:12 INFO - 'other-e10s': {'tests': ['a11yr', 12:09:12 INFO - 'ts_paint', 12:09:12 INFO - 'tpaint', 12:09:12 INFO - 'sessionrestore', 12:09:12 INFO - 'sessionrestore_no_auto_restore', 12:09:12 INFO - 'tabpaint']}, 12:09:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:09:12 INFO - 'ts_paint', 12:09:12 INFO - 'tpaint', 12:09:12 INFO - 'sessionrestore', 12:09:12 INFO - 'sessionrestore_no_auto_restore', 12:09:12 INFO - 'tabpaint']}, 12:09:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:09:12 INFO - 'ts_paint', 12:09:12 INFO - 'tpaint', 12:09:12 INFO - 'sessionrestore', 12:09:12 INFO - 'sessionrestore_no_auto_restore', 12:09:12 INFO - 'tabpaint']}, 12:09:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:09:12 INFO - 'tests': ['a11yr', 12:09:12 INFO - 'ts_paint', 12:09:12 INFO - 'tpaint', 12:09:12 INFO - 'sessionrestore', 12:09:12 INFO - 'sessionrestore_no_auto_restore', 12:09:12 INFO - 'tabpaint']}, 12:09:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:09:12 INFO - 'tests': ['a11yr', 12:09:12 INFO - 'ts_paint', 12:09:12 INFO - 'tpaint', 12:09:12 INFO - 'sessionrestore', 12:09:12 INFO - 'sessionrestore_no_auto_restore', 12:09:12 INFO - 'tabpaint']}, 12:09:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:09:12 INFO - 'tests': ['tsvgx', 12:09:12 INFO - 'tsvgr_opacity', 12:09:12 INFO - 'tart', 12:09:12 INFO - 'tscrollx', 12:09:12 INFO - 'cart']}, 12:09:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:09:12 INFO - 'tsvgr_opacity', 12:09:12 INFO - 'tart', 12:09:12 INFO - 'tscrollx', 12:09:12 INFO - 'cart']}, 12:09:12 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:09:12 INFO - 'talos_options': ['--disable-e10s'], 12:09:12 INFO - 'tests': ['tp5o']}, 12:09:12 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:09:12 INFO - 'tests': ['tp5o']}, 12:09:12 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:09:12 INFO - 'talos_options': ['--disable-e10s', 12:09:12 INFO - '--xperf_path', 12:09:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:09:12 INFO - 'tests': ['tp5n']}, 12:09:12 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:09:12 INFO - 'talos_options': ['--xperf_path', 12:09:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:09:12 INFO - 'tests': ['tp5n']}}, 12:09:12 INFO - 'talos.zip': {'path': '', 12:09:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:09:12 INFO - Running post-action listener: _resource_record_post_action 12:09:12 INFO - ##### 12:09:12 INFO - ##### Running create-virtualenv step. 12:09:12 INFO - ##### 12:09:12 INFO - Running pre-action listener: _resource_record_pre_action 12:09:12 INFO - Running main action method: create_virtualenv 12:09:12 INFO - Creating virtualenv /builds/slave/test/build/venv 12:09:12 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:09:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:09:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:09:12 INFO - Using real prefix '/tools/python27' 12:09:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:09:13 INFO - Installing distribute.............................................................................................................................................................................................done. 12:09:16 INFO - Installing pip.................done. 12:09:16 INFO - Return code: 0 12:09:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:09:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:09:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1039eba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039fb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb324a3d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10388ae00>, '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 0x1036f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103885b20>, '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.TihuaNKSQ9/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.1ZKS69xVfE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:09:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:09:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:09:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TihuaNKSQ9/Render', 12:09:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:16 INFO - 'HOME': '/Users/cltbld', 12:09:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:16 INFO - 'LOGNAME': 'cltbld', 12:09:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:16 INFO - 'MOZ_NO_REMOTE': '1', 12:09:16 INFO - 'NO_EM_RESTART': '1', 12:09:16 INFO - 'PAGER': '/bin/cat', 12:09:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:16 INFO - 'PWD': '/builds/slave/test', 12:09:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:16 INFO - 'SHELL': '/bin/bash', 12:09:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1ZKS69xVfE/Listeners', 12:09:16 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 12:09:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:16 INFO - 'USER': 'cltbld', 12:09:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:16 INFO - 'XPC_FLAGS': '0x0', 12:09:16 INFO - 'XPC_SERVICE_NAME': '0', 12:09:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:16 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 12:09:16 INFO - Cleaning up... 12:09:16 INFO - Return code: 0 12:09:16 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 12:09:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:09:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1039eba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039fb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb324a3d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10388ae00>, '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 0x1036f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103885b20>, '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.TihuaNKSQ9/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.1ZKS69xVfE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:09:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 12:09:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 12:09:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TihuaNKSQ9/Render', 12:09:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:16 INFO - 'HOME': '/Users/cltbld', 12:09:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:16 INFO - 'LOGNAME': 'cltbld', 12:09:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:16 INFO - 'MOZ_NO_REMOTE': '1', 12:09:16 INFO - 'NO_EM_RESTART': '1', 12:09:16 INFO - 'PAGER': '/bin/cat', 12:09:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:16 INFO - 'PWD': '/builds/slave/test', 12:09:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:16 INFO - 'SHELL': '/bin/bash', 12:09:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1ZKS69xVfE/Listeners', 12:09:16 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 12:09:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:16 INFO - 'USER': 'cltbld', 12:09:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:16 INFO - 'XPC_FLAGS': '0x0', 12:09:16 INFO - 'XPC_SERVICE_NAME': '0', 12:09:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:16 INFO - Downloading/unpacking psutil>=0.7.1 12:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:09:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:09:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:09:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:09:19 INFO - Installing collected packages: psutil 12:09:19 INFO - Running setup.py install for psutil 12:09:19 INFO - building 'psutil._psutil_osx' extension 12:09:19 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 12:09:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 12:09:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 12:09:20 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 12:09:20 INFO - building 'psutil._psutil_posix' extension 12:09:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 12:09:20 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:09:20 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:09:20 INFO - ^ 12:09:20 INFO - 1 warning generated. 12:09:20 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 12:09:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:09:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:09:20 INFO - Successfully installed psutil 12:09:20 INFO - Cleaning up... 12:09:20 INFO - Return code: 0 12:09:20 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 12:09:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:09:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:09:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:20 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 0x1039eba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039fb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb324a3d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10388ae00>, '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 0x1036f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103885b20>, '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.TihuaNKSQ9/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.1ZKS69xVfE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:09:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 12:09:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 12:09:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TihuaNKSQ9/Render', 12:09:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:20 INFO - 'HOME': '/Users/cltbld', 12:09:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:20 INFO - 'LOGNAME': 'cltbld', 12:09:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:20 INFO - 'MOZ_NO_REMOTE': '1', 12:09:20 INFO - 'NO_EM_RESTART': '1', 12:09:20 INFO - 'PAGER': '/bin/cat', 12:09:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:20 INFO - 'PWD': '/builds/slave/test', 12:09:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:20 INFO - 'SHELL': '/bin/bash', 12:09:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1ZKS69xVfE/Listeners', 12:09:20 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 12:09:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:20 INFO - 'USER': 'cltbld', 12:09:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:20 INFO - 'XPC_FLAGS': '0x0', 12:09:20 INFO - 'XPC_SERVICE_NAME': '0', 12:09:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:20 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 12:09:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:09:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:09:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:23 INFO - Downloading mozsystemmonitor-0.0.tar.gz 12:09:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:09:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 12:09:23 INFO - Installing collected packages: mozsystemmonitor 12:09:23 INFO - Running setup.py install for mozsystemmonitor 12:09:23 INFO - Successfully installed mozsystemmonitor 12:09:23 INFO - Cleaning up... 12:09:23 INFO - Return code: 0 12:09:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:09:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:09:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:23 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 0x1039eba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039fb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb324a3d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10388ae00>, '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 0x1036f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103885b20>, '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.TihuaNKSQ9/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.1ZKS69xVfE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:09:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:09:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:09:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TihuaNKSQ9/Render', 12:09:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:23 INFO - 'HOME': '/Users/cltbld', 12:09:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:23 INFO - 'LOGNAME': 'cltbld', 12:09:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:23 INFO - 'MOZ_NO_REMOTE': '1', 12:09:23 INFO - 'NO_EM_RESTART': '1', 12:09:23 INFO - 'PAGER': '/bin/cat', 12:09:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:23 INFO - 'PWD': '/builds/slave/test', 12:09:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:23 INFO - 'SHELL': '/bin/bash', 12:09:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1ZKS69xVfE/Listeners', 12:09:23 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 12:09:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:23 INFO - 'USER': 'cltbld', 12:09:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:23 INFO - 'XPC_FLAGS': '0x0', 12:09:23 INFO - 'XPC_SERVICE_NAME': '0', 12:09:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:24 INFO - Downloading/unpacking blobuploader==1.2.4 12:09:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:09:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:09:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:26 INFO - Downloading blobuploader-1.2.4.tar.gz 12:09:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:09:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:09:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:09:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:09:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:09:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:09:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:09:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:09:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:27 INFO - Downloading docopt-0.6.1.tar.gz 12:09:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:09:27 INFO - Installing collected packages: blobuploader, requests, docopt 12:09:27 INFO - Running setup.py install for blobuploader 12:09:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:09:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:09:27 INFO - Running setup.py install for requests 12:09:28 INFO - Running setup.py install for docopt 12:09:28 INFO - Successfully installed blobuploader requests docopt 12:09:28 INFO - Cleaning up... 12:09:28 INFO - Return code: 0 12:09:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:09:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:09:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:09:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1039eba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039fb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb324a3d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10388ae00>, '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 0x1036f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103885b20>, '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.TihuaNKSQ9/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.1ZKS69xVfE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:09:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:09:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:09:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TihuaNKSQ9/Render', 12:09:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:28 INFO - 'HOME': '/Users/cltbld', 12:09:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:28 INFO - 'LOGNAME': 'cltbld', 12:09:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:28 INFO - 'MOZ_NO_REMOTE': '1', 12:09:28 INFO - 'NO_EM_RESTART': '1', 12:09:28 INFO - 'PAGER': '/bin/cat', 12:09:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:28 INFO - 'PWD': '/builds/slave/test', 12:09:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:28 INFO - 'SHELL': '/bin/bash', 12:09:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1ZKS69xVfE/Listeners', 12:09:28 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 12:09:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:28 INFO - 'USER': 'cltbld', 12:09:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:28 INFO - 'XPC_FLAGS': '0x0', 12:09:28 INFO - 'XPC_SERVICE_NAME': '0', 12:09:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:09:28 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-_GJtQY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:09:28 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-uiVC83-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:09:29 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-i3pZUo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:09:29 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-5bqcd0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:09:29 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-NEqaSI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:09:29 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-xZ4lDD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:09:29 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-0VFrLo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:09:29 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-gO5RSs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:09:29 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-_mud9o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:09:29 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-VJskWC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:09:29 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-y8cONi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:09:30 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-Z3U4Sk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:09:30 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-vjM9nA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:09:30 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-lPDLPw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:09:30 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-XinXfl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:09:30 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-kCQxvh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:09:30 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-c5vQw9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:09:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:09:30 INFO - Running setup.py install for manifestparser 12:09:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:09:30 INFO - Running setup.py install for mozcrash 12:09:30 INFO - Running setup.py install for mozdebug 12:09:31 INFO - Running setup.py install for mozdevice 12:09:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:09:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:09:31 INFO - Running setup.py install for mozfile 12:09:31 INFO - Running setup.py install for mozhttpd 12:09:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:09:31 INFO - Running setup.py install for mozinfo 12:09:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:09:31 INFO - Running setup.py install for mozInstall 12:09:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:09:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:09:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:09:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:09:31 INFO - Running setup.py install for mozleak 12:09:31 INFO - Running setup.py install for mozlog 12:09:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:09:32 INFO - Running setup.py install for moznetwork 12:09:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:09:32 INFO - Running setup.py install for mozprocess 12:09:32 INFO - Running setup.py install for mozprofile 12:09:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:09:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:09:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:09:32 INFO - Running setup.py install for mozrunner 12:09:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:09:32 INFO - Running setup.py install for mozscreenshot 12:09:32 INFO - Running setup.py install for moztest 12:09:33 INFO - Running setup.py install for mozversion 12:09:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:09:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:09:33 INFO - Cleaning up... 12:09:33 INFO - Return code: 0 12:09:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:09:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:09:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:09:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1039eba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039fb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb324a3d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10388ae00>, '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 0x1036f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103885b20>, '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.TihuaNKSQ9/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.1ZKS69xVfE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:09:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:09:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:09:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TihuaNKSQ9/Render', 12:09:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:33 INFO - 'HOME': '/Users/cltbld', 12:09:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:33 INFO - 'LOGNAME': 'cltbld', 12:09:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:33 INFO - 'MOZ_NO_REMOTE': '1', 12:09:33 INFO - 'NO_EM_RESTART': '1', 12:09:33 INFO - 'PAGER': '/bin/cat', 12:09:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:33 INFO - 'PWD': '/builds/slave/test', 12:09:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:33 INFO - 'SHELL': '/bin/bash', 12:09:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1ZKS69xVfE/Listeners', 12:09:33 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 12:09:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:33 INFO - 'USER': 'cltbld', 12:09:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:33 INFO - 'XPC_FLAGS': '0x0', 12:09:33 INFO - 'XPC_SERVICE_NAME': '0', 12:09:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:09:33 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-7RraPH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:09:33 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 12:09:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:09:33 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-nq9CNu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:09:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:09:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:09:33 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-hQayaJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:09:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:09:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:09:33 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-AqFTeK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:09:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:09:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:09:33 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-sWFFkj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:09:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 12:09:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:09:33 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-OtcAH9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:09:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 12:09:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:09:34 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-AKlAoz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:09:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 12:09:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:09:34 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-TbFLP6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:09:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 12:09:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:09:34 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-KU4i7R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:09:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 12:09:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:09:34 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-H__xH3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:09:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:09:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:09:34 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-7g08kX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:09:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 12:09:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:09:34 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-7ba19z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:09:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 12:09:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:09:34 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-n2_DmA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:09:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 12:09:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:09:34 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-2koSIM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:09:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 12:09:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:09:34 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-r6gJCA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:09:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 12:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:09:35 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-nr5qmh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:09:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 12:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:09:35 INFO - Running setup.py (path:/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/pip-xu_JHh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:09:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 12:09:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:09:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:09:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:09:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:09:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:09:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:09:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:09:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:09:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:37 INFO - Downloading blessings-1.6.tar.gz 12:09:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:09:37 INFO - Installing collected packages: blessings 12:09:37 INFO - Running setup.py install for blessings 12:09:37 INFO - Successfully installed blessings 12:09:37 INFO - Cleaning up... 12:09:37 INFO - Return code: 0 12:09:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:09:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:09:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:09:38 INFO - Reading from file tmpfile_stdout 12:09:38 INFO - Current package versions: 12:09:38 INFO - blessings == 1.6 12:09:38 INFO - blobuploader == 1.2.4 12:09:38 INFO - docopt == 0.6.1 12:09:38 INFO - manifestparser == 1.1 12:09:38 INFO - mozInstall == 1.12 12:09:38 INFO - mozcrash == 0.17 12:09:38 INFO - mozdebug == 0.1 12:09:38 INFO - mozdevice == 0.48 12:09:38 INFO - mozfile == 1.2 12:09:38 INFO - mozhttpd == 0.7 12:09:38 INFO - mozinfo == 0.9 12:09:38 INFO - mozleak == 0.1 12:09:38 INFO - mozlog == 3.1 12:09:38 INFO - moznetwork == 0.27 12:09:38 INFO - mozprocess == 0.22 12:09:38 INFO - mozprofile == 0.28 12:09:38 INFO - mozrunner == 6.11 12:09:38 INFO - mozscreenshot == 0.1 12:09:38 INFO - mozsystemmonitor == 0.0 12:09:38 INFO - moztest == 0.7 12:09:38 INFO - mozversion == 1.4 12:09:38 INFO - psutil == 3.1.1 12:09:38 INFO - requests == 1.2.3 12:09:38 INFO - wsgiref == 0.1.2 12:09:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:09:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:09:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:09:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1039eba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039fb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb324a3d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10388ae00>, '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 0x1036f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103885b20>, '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.TihuaNKSQ9/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.1ZKS69xVfE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:09:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 12:09:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:09:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TihuaNKSQ9/Render', 12:09:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:38 INFO - 'HOME': '/Users/cltbld', 12:09:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:38 INFO - 'LOGNAME': 'cltbld', 12:09:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:38 INFO - 'MOZ_NO_REMOTE': '1', 12:09:38 INFO - 'NO_EM_RESTART': '1', 12:09:38 INFO - 'PAGER': '/bin/cat', 12:09:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:38 INFO - 'PWD': '/builds/slave/test', 12:09:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:38 INFO - 'SHELL': '/bin/bash', 12:09:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1ZKS69xVfE/Listeners', 12:09:38 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 12:09:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:38 INFO - 'USER': 'cltbld', 12:09:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:38 INFO - 'XPC_FLAGS': '0x0', 12:09:38 INFO - 'XPC_SERVICE_NAME': '0', 12:09:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:09:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 12:09:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 12:09:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:09:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 12:09:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 12:09:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 12:09:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 12:09:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 12:09:38 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:09:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:09:38 INFO - Cleaning up... 12:09:38 INFO - Return code: 0 12:09:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:09:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:09:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:09:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1039eba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039fb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb324a3d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10388ae00>, '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 0x1036f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103885b20>, '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.TihuaNKSQ9/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.1ZKS69xVfE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:09:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:09:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:09:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TihuaNKSQ9/Render', 12:09:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:38 INFO - 'HOME': '/Users/cltbld', 12:09:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:38 INFO - 'LOGNAME': 'cltbld', 12:09:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:38 INFO - 'MOZ_NO_REMOTE': '1', 12:09:38 INFO - 'NO_EM_RESTART': '1', 12:09:38 INFO - 'PAGER': '/bin/cat', 12:09:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:38 INFO - 'PWD': '/builds/slave/test', 12:09:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:38 INFO - 'SHELL': '/bin/bash', 12:09:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1ZKS69xVfE/Listeners', 12:09:38 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 12:09:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:38 INFO - 'USER': 'cltbld', 12:09:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:38 INFO - 'XPC_FLAGS': '0x0', 12:09:38 INFO - 'XPC_SERVICE_NAME': '0', 12:09:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:38 INFO - Downloading/unpacking jsonschema 12:09:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:09:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:09:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:09:41 INFO - Downloading/unpacking functools32 (from jsonschema) 12:09:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:09:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:09:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:41 INFO - Downloading functools32-3.2.3-2.tar.gz 12:09:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:09:41 INFO - warning: no files found matching '*.txt' 12:09:41 INFO - no previously-included directories found matching 'build' 12:09:41 INFO - no previously-included directories found matching 'dist' 12:09:41 INFO - no previously-included directories found matching '.git*' 12:09:41 INFO - Installing collected packages: jsonschema, functools32 12:09:41 INFO - Running setup.py install for functools32 12:09:41 INFO - warning: no files found matching '*.txt' 12:09:41 INFO - no previously-included directories found matching 'build' 12:09:41 INFO - no previously-included directories found matching 'dist' 12:09:41 INFO - no previously-included directories found matching '.git*' 12:09:41 INFO - Successfully installed jsonschema functools32 12:09:41 INFO - Cleaning up... 12:09:42 INFO - Return code: 0 12:09:42 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 12:09:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:09:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:09:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1039eba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1039fb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb324a3d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10388ae00>, '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 0x1036f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103885b20>, '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.TihuaNKSQ9/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.1ZKS69xVfE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:09:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 12:09:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 12:09:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TihuaNKSQ9/Render', 12:09:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:42 INFO - 'HOME': '/Users/cltbld', 12:09:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:42 INFO - 'LOGNAME': 'cltbld', 12:09:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:42 INFO - 'MOZ_NO_REMOTE': '1', 12:09:42 INFO - 'NO_EM_RESTART': '1', 12:09:42 INFO - 'PAGER': '/bin/cat', 12:09:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:42 INFO - 'PWD': '/builds/slave/test', 12:09:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:42 INFO - 'SHELL': '/bin/bash', 12:09:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1ZKS69xVfE/Listeners', 12:09:42 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 12:09:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:42 INFO - 'USER': 'cltbld', 12:09:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:42 INFO - 'XPC_FLAGS': '0x0', 12:09:42 INFO - 'XPC_SERVICE_NAME': '0', 12:09:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:42 INFO - Downloading/unpacking flake8 12:09:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:09:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:09:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:44 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 12:09:44 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 12:09:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:09:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:09:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:45 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 12:09:45 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 12:09:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:09:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:09:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:45 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 12:09:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:09:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:09:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 12:09:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:09:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:09:45 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:09:45 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:09:45 INFO - no previously-included directories found matching 'docs/_build' 12:09:45 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 12:09:46 INFO - Running setup.py install for pep8 12:09:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:09:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:09:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:09:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:09:46 INFO - no previously-included directories found matching 'docs/_build' 12:09:46 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 12:09:46 INFO - Successfully installed flake8 mccabe pyflakes pep8 12:09:46 INFO - Cleaning up... 12:09:46 INFO - Return code: 0 12:09:46 INFO - Running post-action listener: _resource_record_post_action 12:09:46 INFO - Running post-action listener: _start_resource_monitoring 12:09:46 INFO - Starting resource monitoring. 12:09:46 INFO - ##### 12:09:46 INFO - ##### Running install step. 12:09:46 INFO - ##### 12:09:46 INFO - Running pre-action listener: _resource_record_pre_action 12:09:46 INFO - Running main action method: install 12:09:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:09:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:09:46 INFO - Reading from file tmpfile_stdout 12:09:46 INFO - Detecting whether we're running mozinstall >=1.0... 12:09:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:09:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:09:46 INFO - Reading from file tmpfile_stdout 12:09:46 INFO - Output received: 12:09:46 INFO - Usage: mozinstall [options] installer 12:09:46 INFO - Options: 12:09:46 INFO - -h, --help show this help message and exit 12:09:46 INFO - -d DEST, --destination=DEST 12:09:46 INFO - Directory to install application into. [default: 12:09:46 INFO - "/builds/slave/test"] 12:09:46 INFO - --app=APP Application being installed. [default: firefox] 12:09:46 INFO - mkdir: /builds/slave/test/build/application 12:09:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:09:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 12:10:18 INFO - Reading from file tmpfile_stdout 12:10:18 INFO - Output received: 12:10:18 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 12:10:18 INFO - Running post-action listener: _resource_record_post_action 12:10:18 INFO - ##### 12:10:18 INFO - ##### Running run-tests step. 12:10:18 INFO - ##### 12:10:18 INFO - Running pre-action listener: _resource_record_pre_action 12:10:18 INFO - Running main action method: run_tests 12:10:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:10:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:10:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:10:18 INFO - 2016-05-26 12:10:18.090 screenresolution[1931:11704] starting screenresolution argv=screenresolution get 12:10:18 INFO - 2016-05-26 12:10:18.093 screenresolution[1931:11704] Display 0: 1600x1200x32@60 12:10:18 INFO - 2016-05-26 12:10:18.098 screenresolution[1932:11706] starting screenresolution argv=screenresolution list 12:10:18 INFO - Available Modes on Display 0 12:10:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:10:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:10:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:10:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:10:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:10:18 INFO - Intel Iris: 12:10:18 INFO - Chipset Model: Intel Iris 12:10:18 INFO - Type: GPU 12:10:18 INFO - Bus: Built-In 12:10:18 INFO - VRAM (Dynamic, Max): 1536 MB 12:10:18 INFO - Vendor: Intel (0x8086) 12:10:18 INFO - Device ID: 0x0a2e 12:10:18 INFO - Revision ID: 0x0009 12:10:18 INFO - Displays: 12:10:18 INFO - Display: 12:10:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:10:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:10:18 INFO - Display Serial Number: 1600x1200 60 12:10:18 INFO - Main Display: Yes 12:10:18 INFO - Mirror: Off 12:10:18 INFO - Online: Yes 12:10:18 INFO - Rotation: Supported 12:10:18 INFO - Return code: 0 12:10:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:10:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:10:18 INFO - Python 2.7.3 12:10:18 INFO - Return code: 0 12:10:18 INFO - grabbing minidump binary from tooltool 12:10:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:10:18 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 0x10388ae00>, '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 0x1036f3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103885b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:10:18 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 12:10:18 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 12:10:18 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:10:18 INFO - Return code: 0 12:10:18 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:10:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:10:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:10:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:10:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:10:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 12:10:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 12:10:19 INFO - Return code: 0 12:10:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Tt_p062KSx-6sp0njp_UEA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0244', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 12:10:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Tt_p062KSx-6sp0njp_UEA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0244 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 12:10:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TihuaNKSQ9/Render', 12:10:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:10:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:10:19 INFO - 'HOME': '/Users/cltbld', 12:10:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:10:19 INFO - 'LOGNAME': 'cltbld', 12:10:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:10:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:10:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:10:19 INFO - 'MOZ_NO_REMOTE': '1', 12:10:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:10:19 INFO - 'NO_EM_RESTART': '1', 12:10:19 INFO - 'PAGER': '/bin/cat', 12:10:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:10:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:10:19 INFO - 'PWD': '/builds/slave/test', 12:10:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:10:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:10:19 INFO - 'SHELL': '/bin/bash', 12:10:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1ZKS69xVfE/Listeners', 12:10:19 INFO - 'TMPDIR': '/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/', 12:10:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:10:19 INFO - 'USER': 'cltbld', 12:10:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:10:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:10:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:10:19 INFO - 'XPC_FLAGS': '0x0', 12:10:19 INFO - 'XPC_SERVICE_NAME': '0', 12:10:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:10:19 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Tt_p062KSx-6sp0njp_UEA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0244', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 12:10:19 INFO - mozversion application_buildid: 20160526100254 12:10:19 INFO - mozversion application_changeset: 3a217924ee0636422a9b14e96f774d9cf7404ac9 12:10:19 INFO - mozversion application_display_name: FirefoxDeveloperEdition 12:10:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:10:19 INFO - mozversion application_name: Firefox 12:10:19 INFO - mozversion application_remotingname: firefox-dev 12:10:19 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:10:19 INFO - mozversion application_vendor: Mozilla 12:10:19 INFO - mozversion application_version: 48.0a2 12:10:19 INFO - mozversion platform_buildid: 20160526100254 12:10:19 INFO - mozversion platform_changeset: 3a217924ee0636422a9b14e96f774d9cf7404ac9 12:10:19 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:10:19 INFO - mozversion platform_version: 48.0a2 12:10:19 INFO - using testdate: 1464289819 12:10:19 INFO - actual date: 1464289819 12:10:19 INFO - starting webserver on 'localhost:49562' 12:10:19 INFO - SUITE-START | Running 2 tests 12:10:19 INFO - TEST-START | dromaeo_css 12:10:19 INFO - operating with platform_type : mac_ 12:10:19 INFO - Initialising browser for dromaeo_css test... 12:10:19 INFO - TEST-INFO | started process 1950 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/tmp7FBzNX/profile http://localhost:49562/getInfo.html) 12:10:21 INFO - PROCESS | 1950 | __metrics Screen width/height:1600/1200 12:10:21 INFO - PROCESS | 1950 | colorDepth:24 12:10:21 INFO - PROCESS | 1950 | Browser inner width/height: 1024/689 12:10:21 INFO - PROCESS | 1950 | __metrics 12:10:21 INFO - PROCESS | 1950 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:10:21 ERROR - PROCESS | 1950 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 12:10:22 INFO - TEST-INFO | 1950: exit 0 12:10:22 INFO - Browser initialized. 12:10:22 INFO - Running cycle 1/1 for dromaeo_css test... 12:10:22 INFO - TEST-INFO | started process 1952 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/tmp7FBzNX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:10:34 INFO - PROCESS | 1952 | RSS: Main: 211533824 12:10:34 INFO - PROCESS | 1952 | 12:11:26 INFO - PROCESS | 1952 | 1464289886117 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 12:13:37 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:49562/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49562/tests/dromaeo/cssquery-ext.html) 12:13:38 INFO - PROCESS | 1952 | RSS: Main: 331984896 12:13:38 INFO - PROCESS | 1952 | 12:16:41 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:49562/tests/dromaeo/cssquery-ext.html (next: http://localhost:49562/tests/dromaeo/cssquery-jquery.html) 12:16:42 INFO - PROCESS | 1952 | RSS: Main: 358109184 12:16:42 INFO - PROCESS | 1952 | 12:19:44 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:49562/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49562/tests/dromaeo/cssquery-mootools.html) 12:19:44 INFO - PROCESS | 1952 | RSS: Main: 359755776 12:19:44 INFO - PROCESS | 1952 | 12:22:46 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:49562/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49562/tests/dromaeo/cssquery-prototype.html) 12:22:47 INFO - PROCESS | 1952 | RSS: Main: 360169472 12:22:47 INFO - PROCESS | 1952 | 12:25:50 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:49562/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49562/tests/dromaeo/cssquery-yui.html) 12:25:51 INFO - PROCESS | 1952 | RSS: Main: 334942208 12:25:51 INFO - PROCESS | 1952 | 12:28:54 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:49562/tests/dromaeo/cssquery-yui.html (next: http://localhost:49562/tests/dromaeo/cssquery-dojo.html) 12:28:54 INFO - PROCESS | 1952 | RSS: Main: 364003328 12:28:54 INFO - PROCESS | 1952 | 12:28:54 INFO - PROCESS | 1952 | __start_tp_report 12:28:54 INFO - PROCESS | 1952 | _x_x_mozilla_page_load 12:28:54 INFO - PROCESS | 1952 | _x_x_mozilla_page_load_details 12:28:54 INFO - PROCESS | 1952 | |i|pagename|runs| 12:28:54 INFO - PROCESS | 1952 | |0;dojo.html;3299.356625458035;3375.409303371909;3377.1725927147836;3384.4415671414217;3432.9828350858256;4541.772911354431;5483.424240454755;5492.725363731817;5494.835154945353;5509.118541033436;5453.23654688344;5498.945010549894;5509.669419834812;5519.613627046108;5525.889482210363;4988.950110498885;5033.647644664884;5082.644214904958;5102.438731739506;5128.230765385203;5016.423111342187;5087.338645976018;5102.851858331865;5106.66806657566;5121.462246464123;5072.26452164436;5112.182050871843;5113.565346945514;5130.025295193901;5148.665336753118;5131.846044618686;5141.485851414866;5145.922811157836;5164.948350516484;5200.6619569727845;4927.6304277179;4977.925331120036;5026.070177017227;5099.515546023122;5100.209467532502;5350.2777125088005;5384.1923711443205;5388.757505912244;5458.372287186945;5505.421930697259;6127.662978536178;6129.84675383119;6130.877382452326;6132.417420345105;6132.846678833021;6054.788082417138;6181.567290289681;6204.503639708858;6210.782622608187;6216.533759967975;5618.943810561883;5653.745581448804;5666.433356664383;5735.684537350486;5748.626339287933;6287.49700023997;6316.810495685137;6326.5255105867245;6332.398422149889;6337.2395312562785;5877.617954832922;5999.310079340845;6010.699465026732;6020.488258497989;6044.214252147192;6101.938980610137;6130.601510901777;6137.070562331796;6157.076438534255;6166.075088736726;5993.700314984233;6035.698215089315;6045.516358691294;6052.727627256696;6082.118092876508;5958.76164953397;6047.092936059538;6074.51403887688;6090.543209259324;6103.633781973096;4786.7845946932475;4824.517548245218;4825.010872771144;4830.275458681155;4831.710097394168;4286.5284818669925;4289.034967132433;4327.805248763813;4353.869383918487;4382.474103107584;5566.471185237396;5591.552675785927;5608.074667679787;5618.073017952074;5638.23883775685;6119.969400152971;6144.56988010835;6156.78451254204;6158.876822463526;6164.660943648053;5446.428125046813;5504.614676972574;5568.832935011957;5577.05190117681;5596.608237423423;5248.606354523426;5266.947330526722;5267.6576022559175;5269.367675878919;5274.208868669731;6220.098085777628;6274;6287.245530536365;6305.842353941188;6332.651704156131;6169.2288463942;6204.3795620439405;6209.689515524296;6210.596311239755;6228.128062071223;5337.359516858002;6251.156093927261;6288.622682639057;6290.716917738621;6305.180326557518;5867.765289388377;5936.703164841827;5961.850953726192;5972.432618901197;6088.817335479943;5138;6012.699365031818;6034.8793024138395;6066.605670631395;6105;5875.50058245054;5910.940890591039;5911.733971971357;5931.3178984417345;5944.227250457413;5921.940780592139;5928.229330187048;5930;5934.1988831508925;5938.792142275;5283.260343551982;5655.858603534944;5773.191753154646;5782.277215348082;5808.157817116579;5951.047832346804;5968.134620480093;5971.850703732442;5999.070144127667;6001.279846418283;5763.567732420001;5785.334686511105;5809.157672137432;5818.981678206382;5819.243498345188 12:28:54 INFO - PROCESS | 1952 | |1;ext.html;496.1342457411817;511.80295586199304;529.1216580476407;533.1443033930541;538.7710223155158;27696.861515692417;31145.909893153745;37247.81376093119;38126.80936595313;38231.617683823155;40782.18435631286;41250;41288;41319;41322.79338603303;9304.441733495978;9535.80928381432;9538.761530961712;9556.522173891313;9570.617175312978;8487.363447741443;8672.046074931752;11421.714457138596;11594.362310072942;11613.361265130414;11337.659870203906;11437.771244575104;11446.885531144666;11450.969412752851;11545.422728863565;8560.871586926201;11347.43262836859;11446.427678616035;11453.427328633577;11592.362420066893;11314.490847911864;11429;11433.599824006204;11454.198206125575;11470.311781293149;37791.811040944616;38024.42963355552;38169.61830381688;38237;38308.80845595782;3205.7114859662743;3341.198112452994;3383.678550537695;3505.5793304803333;3691.2063906260014;27235.727642723516;28756.712432875822;29193.85403072971;29227.97702080438;29228.538573071473;2735.9452810943867;2743.2044707034934;2759.351552385188;2893.5225687761495;2896.0877323642985;351300.4869951293;353373;354133.99999999744;354135;354517;36003.27993440169;36583.26833463316;58933.705331473924;59440.40559594435;59448.70275648594;35358.46962295516;35607.64392356095;35759.92720218398;35795.82102089473;35859.4621080684;12794.296313702836;12807;15285.388584456497;15452.459163929208;15467.99458035224;12169.756604868031;12434.943029842383;13018.869811301955;13049.478020879249;13097.672558185932;6888.242293347727;7994.6002699864775;7999.400044996649;8091.312238459679;8108.2297181767635;3169.9380707462797;3205.1346136543;3344.8494817733263;3401.693847553732;3439.7764145330475;1012.5747186181821;1026;1057.8677665291839;1117.3407689463256;1119.339589642115;24585.84581065675;28823.2794180143;29836.850815746217;30072.699273007427;30266.697333026827;795.4869109424388;1066.6160182334509;1103.7902798468426;1104.4808939798281;1141.1042331769522;14230.43078276878;14406;14820.110793352218;14927.029743066665;15561.377544898078;480527;482568;486329;489897;491195;60708.392916071156;61331.693341533006;61333.08000379998;61385.693071534355;61434;1340.222670850904;1455.7670772676322;1487.7173337065922;1493.0817547208605;1493.6788590453104;28087.859560702065;28347.974718765363;28791.568126478538;29501.409971800014;29833.25416864596;48769;49453.50546494633;49613.75193124011;49653;49680.25479617736;519003;522500;523234;523349;524221;22465;25447.491050179262;25717.614235786852;25963.74036259613;25987.96048158128;32763.508547372287;39510.40734388929;40025.79987100046;41119.38320925248;41670.58329416788;33064.842730504315;33380;34556.65443345635;34702.9589112327;35211.64788352186;29046.98335558245;33641.495377569845;33901.66098339084;34453.82773086119;34605.30789384249;34563.13592160216;34924.65075349316;35714.10714732153;35718;35789.46315805213;30534.694653053186;31285.95600676783;31291.84354078215;31336.843315783277;31406.842965785025;23858.880705596363;23958.640620391056;24311.27066188017;24315.39211519726;24354.634680479452 12:28:54 INFO - PROCESS | 1952 | |2;jquery.html;405.53161098930735;409.3287009304739;409.38796499233644;412.4205491284746;421.919835231306;18896.62206755864;19747.1113799879;20439.386818395455;20446.2843800467;20462.386128416136;19343.4196974091;19780.912049837254;20106.798932010675;20179.19283228669;20235.696464553042;5661.886762264752;5727.914081288784;5799.420057994188;5845.415458454163;5920.407959204067;5822.563307751935;5852.853678658046;5862.618929769573;5881.735321910524;5882.411758824126;5576.247206627114;5736.426357364272;5798.072308430652;5828;5846.88306233875;5680.233168522259;5776.971115144397;5781.306243250795;5800.361960184377;5828.97085514574;5016.849494515171;5099.33708617881;5103.642745007851;5105.234214867763;5111.361079865017;24703;25035.749642503706;25404.85678144488;25449.87275063613;25460.236192914243;2210.856294340863;2257.2776711452343;2278.6126358518927;2321.4892723600783;2354.8233882458885;14310;14553.708925821531;14597.343119559646;15186.924065379713;15221.31504082319;1682.167327173045;1734.453647101159;1737.9913100434417;1771.3268957796083;1780.2789870102629;92576.53711731466;113579;113897.43051284773;114887.85112148855;117222.41388793087;10452.320599161183;10673.41296228715;11061.170412219115;11095.167862410352;11205.943970280096;13899.444022239199;13990.580282591538;14019;14025;14028.438862445388;7282.927170728331;8716.259117974916;8828.77928051791;8873.64505419776;8917.42036767608;8934.195922367018;8972.461652300883;8975.640974361084;9034.63861445548;9074.546272686339;6180.6291622502795;6598.241202261705;6598.274189839114;6603.636799976048;6616.86766264672;1754.2807448946126;1874.5313671582105;1906.8283854453166;1909.990450047741;1911.8470522358177;746.7199800074972;921.8525035994209;976.4873441443299;992.335135459244;1028.0541901450683;14584.270786460635;16224;20197.899010504854;20546.589068218553;20963.580728385346;970.8834939807268;974.9902500975041;984.2519685039422;999.1956475037664;1034.5154703001217;10351;11111.44442777874;11173.497192626352;11188.66434006981;11199.040167970572;611237;624035;624895;640905;653523;130617;135541;138012;138106;140589.29705351408;1027.2552399510444;1036.896310368957;1061.7345663584103;1063.9946800266105;1066.024587502442;17480.65038699244;17619.392727727103;17728.936263824213;17847.37534186297;17964.73052904181;47886.28170577374;50371.74814126053;50989.725256867394;52204.73897630639;52604.21093683521;71905.6404717994;72299;73492.16269593303;76469;77212.2278777205;7706.614669266402;7808.726694565663;8142.226488483585;8165.959170204349;8207;12116.939415303219;12248.95883849847;12268.447919843808;12507.93746031264;12574.931130853614;10490.580376785148;11014;11043.503042363274;11055.778884422105;11077.612283570359;10205.897941020496;10279.126274266771;10503.474826258504;10739.624113156002;11161.218714689894;10175.898241017496;10808.729781755519;11024.50389732448;11104.333739975627;11215.046721028477;7741;7903.762887113396;8021.197880211932;8141.43009989295;8167.224113709188;9516.524173791144;10714.73213169677;10763.623273185402;10806.081483073713;11018.614348498084 12:28:54 INFO - PROCESS | 1952 | |3;mootools.html;1423.679672073784;1572.7562227854683;1592.773564355446;1824.7536582561359;1944.5527528668397;7704.2295770423;8349.874751878719;8502.659893604265;8530.786730331736;8554.358423118276;6842.452603791698;8496.235338819504;8502.404831661786;8509.19162679546;8520.531370774605;2106.9894650526803;2332.2536788227776;2377.2155188787747;2389.701287339087;2410.6143017117265;2408.254015838512;2419.8669073200967;2421.7336093029753;2428.3200703802963;2432.6959130108735;1987.7316562264054;2309.2033248529315;2366.8343215974887;2371.6086845670443;2378.369732021016;2097.4861159015986;2339;2365;2377.679013333204;2391.9162829301067;2361.456864921078;2402.939926501834;2403.098837935774;2403.975960240393;2409.132712223598;8404.537750423724;8454.196851299117;8471.407001509897;8475.025372082227;8481.787955301104;5050.444451110375;5184.948150518522;5190.06578815813;5225.268462415265;5225.895482090337;2918.8324670131906;3331.816750078745;3400.1669590950128;3492.4412094065083;3521;1047.314009323894;1051.1958351860833;1057.7461409261798;1058.4284486377346;1061.7451811565245;11368.60209892658;11398.886011139866;11405.771884562262;11425.942870285679;11462.140339474572;4499.470180138768;4548.56788605082;4688.132695451352;4693;4693.741844198602;9680.419174849532;9724.465154416575;9746;9755.951220243853;9766.75583110428;5213.374395072616;5224.373075230997;5242.423333433319;5251.973740131275;5267.578593712494;4297.59172878576;4397.73613583186;4537.682362234678;4733.313669517901;4807.495213002654;3842.1355195080882;3844.8077596120083;3898.785566793797;3936;3946.6053394660303;1297.7274772297735;1298.4226310773108;1299.5451591942744;1310.0960337367376;1316.5918565244804;847.8092429203504;879.9736007919772;920.1216870889101;925.4678559828126;934.6962237272913;5660.349059858087;5673.3475650299915;5725.942740572625;5801.187833703285;5832.475077243069;460.82488654311146;464.03017693021746;467.17543535659587;468.4425533614987;478.9664723469324;3773.113318370181;3783.9621603784162;3841.750286231386;3865.4781604483037;3932.7640341579595;8345.916540834636;10099.394036357695;10133.746656333504;10301.948490257651;10375.481225938673;10085.697429076992;10098.596056157672;10211.795764084825;10222.437765922945;10240.334378265388;7891.526508409399;7922.524648521107;7967.163447838009;8187.83624327522;8188.467749596257;4753.5008824072975;5721.170430287668;5756.395578464369;5817.825465236049;5946.643201407929;9129.634814607341;9259.583318750807;9380.67167649129;9400.34197606161;9527.428354298487;9719.708408747749;9744.025597440199;9820.361676491006;9827.164691001344;9837.163841073589;6595.505337099833;6610.8016759497295;7117.430605551647;7242.529235599669;7382;7913.881291780513;7985.8402831944195;8046.47697899658;8047.396445266511;8052.114267430461;7067.328603782633;7180.281971802778;7212.098487689039;7311.049563556704;7346.926530734838;7037.824054398477;7076;7080.185778635317;7096.503244772816;7214;6752.966235168793;6832.214295356098;6855.177378714586;6862.4853136014;6863.622500762306;4733.763311834464;4734.668573199843;4750.8337208197545;4868.659193856396;4970.527799859007;4722.433308002925;4765.499622539722;4817.445993710628;4822.180229361087;4877.731724755173 12:28:54 INFO - PROCESS | 1952 | |4;prototype.html;318.8007495315429;326.5428400239664;335.5151805640849;337.399429016351;339.92521645238037;5909.109085092767;5966.433188847052;6595.835104122393;6663.366980136885;6675.499337549691;6440.484761219103;6585.341465853412;6596.637184954826;6602.207735071786;6619.139511863463;4977.452480227172;4982.128127577659;5000.3499545059185;5005.499450054984;5011.874703132415;4990.401151861782;4992.67547609406;5010.599152067844;5014.8244811431605;5018.874528136807;4683.929741053887;4744.810207591692;4943.604511639078;4952.603791696673;4955.355803745526;4842.370491836074;4855.126077306101;4858.295547145644;4862.878428039292;4891.902161956777;3940.601086614239;4135.028268356934;4166.395872598457;4167.416561681367;4172.499300083979;5622.606417550773;6420.293767685551;6423.454006409461;6424.486041116699;6433.453156481704;5797.652140871562;5809;5810.157527158581;5814.563907706896;5814.592978491507;6792.45660347176;7069.36375726182;7079.185893622196;7083.858322833566;7093.574385536833;4549.727016378994;4591.357209990604;4594.954050459486;4602;4607.493175750665;7477;7550.583813179785;7574.5076570022775;7588.506747061479;7614.428917831129;4577.3362862384765;4704.670673052853;4706.364640773451;4708.6468514860835;4711.646626503026;4735.431748190239;4756.381670382828;4758.119747846659;4767.308740232646;4775.140474714515;5670.489655930986;5682.289713785777;5684.6020778545935;5693.572982026365;5701.2018317435595;5918.816236752667;7173.89239161413;7195.89206161908;7209.134903811471;7215.242399547972;1528.4879565345734;1643.8356164383465;1645.5803770038563;1647.2422685564534;1648.3489021836547;957.1861322793407;967.9661211857551;984.9655262065793;1002.2082554781787;1037.9377237365782;6362.777302794472;6673.366030227121;6809.489288303298;6847.075644787989;6852.5203235773015;7482.4388170887405;7535.924640753522;7554.962225188949;7555.697772089165;7578.886316705254;6779.084823548757;6781.287964763727;6788.8302792429595;6793;6823.69293381799;3931.292367373843;3938.5076865391825;3961.8613348532663;3972.5431575368625;3986.621270979252;7607.771766847003;7901.644426000844;7932.84134317322;7933.56365399897;7937.8412431752195;7709.2290770922455;7748.496347737379;7755.069391673035;7760.068791744914;7760.9223907761325;6443.80789553934;7905.802354941287;7908.3673306136525;7912;7931.0172456885775;6009.549283803644;7150.42796576283;7437.962810185914;7445.478816483011;7457.440691948017;7348.375388091858;7362.5214361066355;7367.5211111277595;7368.815779605338;7388.630568471663;7896.170902055202;7902.589710138233;7907.130215676156;7927.286544211049;7930.841383172419;4674.6026587740425;4678.742669153229;4684.718916864999;4684.882877928079;4710.340552322746;7152.642367881689;7419.406447484081;7423.517471364344;7432.219616940143;7438.776836694691;6182.814515564541;6579.374959378852;6987;7134.607596582237;7191.137063552198;5799.681017544076;7051.435885129074;7171.354578087997;7222.98878157048;7245.203027666847;7028.332308430691;7066.470014748812;7068.717251310096;7072.939059140964;7089.326513981164;7210.603416812126;7212.675429605785;7213.855722885408;7214.783556493314;7226.458015648742;6760.1549806274215;7064.222935476991;7069.540479868998;7079.752208672672;7084.468664850054 12:28:54 INFO - PROCESS | 1952 | |5;yui.html;689.8103021669041;691.3673988300703;713.5825542057893;761.1589193940692;778.6612823421812;14579.271036448175;14846.628834279121;14901.776473352907;14956.32696528656;15040.473583424582;13625.1143675661;13905.860941390609;13910.652233694163;13934.721305573883;13947.232902190375;704.623026680726;756.5007095317092;897.8294124116397;944.0842382888587;945.7777422305787;930.906909309067;955.3312681123207;955.5890966884238;958.3100167879115;959.7840485890689;824;973.1728031173517;992.4789485520088;995.5569771451707;998.2962011781676;779.9181085986003;784.6390660296271;786.3944762532818;846.9788255293605;989.2877128467528;585.4292065236403;716.4662326566689;730.0217708270942;730.5835673666013;733.2777214443769;14671.926640366837;14725.779113313309;14746.631334216623;14750.55748327552;14765.261736913111;12006.399680015964;12159.817602735966;12290.938545307306;12433.564825231164;12481.31352775597;9986.101250887383;10167.08496235335;10245.385276883337;10613;11136.498857551429;3418.453047512411;3474.947875781865;3480.6519348065244;3496;3712.1647629283466;70952;71934.64032679856;72013.6399318;72340.63829680817;83436;9298.581563829644;9377.578008989476;9422;9456.290778191664;9731.41611503312;20289.39131826018;20410.081546330155;20417.387478375673;20462.488437789176;20535.17859285612;7252.093488313961;7406.444516661164;7500.924990750132;7557.395408367317;7575.356094731899;4109.671226301889;4117.56765539614;4118.464599602033;4123.422720819088;4135.855245066408;2934.207763903734;3449.534312867781;3453.8100404477996;3516.7889926604485;3523.7533372663665;621.8041316985135;622.2470810319564;640.3916279534461;648.5557393185588;649.2955143669077;482.63319876893814;511.9846404607793;580.8286555466148;598.7396530303648;631.6462780842743;7767.2232776723;7885.5268683878;7937.285644292041;8111.1888811118415;8160.959195204105;484.3146947069927;575.8128608202351;592.1473078766562;602.1780269931512;604.4680681000779;3776.6978641709147;3795.6773674237998;3825.2923209206388;3887.125396785757;3958.9010274742786;80803.59598201972;83929.16070839214;83968;84550.15449845546;84649.57675211584;68121.3187868125;69188;69875.65062174656;70017.64991175011;70196.59606807835;63388.366116339166;63917.68041159764;64343;64433.67783161148;64560.677196614655;7538.802660753801;7619.809504762426;7646.885296720665;7765.961170194113;7860.606969651609;45057.549424505334;45637.77181114206;45788.771056144506;46227.76886115548;47488.762556187;58274.543136421926;61674;62286.06570901349;63379.36620633735;63946.68026659837;9617.519124043909;9743.56153973087;9808.215342772704;9999.950000249952;10230.181585472996;10518.105960993402;10643.627473038407;10809.621663241749;10856.45717714127;11623.59317423886;8620.353473489387;8857.556393568484;10230.181585472996;10322.690319290434;11836.993855522376;10948;11067.944660276647;11233.887661123284;11266;11338.546458141582;10749.14006879432;10928.289661171999;11073.50169242402;11117.610883619358;11542.13433992437;9446.24430045581;9550.617975280911;9556.713298601053;9590.328676992543;9679.322447428613;9313.25493960468;9562.378445401027;9619.711408657751;9634;9681.515924203903 12:28:54 INFO - PROCESS | 1952 | __end_tp_report 12:28:54 INFO - PROCESS | 1952 | __start_cc_report 12:28:54 INFO - PROCESS | 1952 | _x_x_mozilla_cycle_collect,1131 12:28:54 INFO - PROCESS | 1952 | __end_cc_report 12:28:54 INFO - PROCESS | 1952 | __startTimestamp1464290934409__endTimestamp 12:28:54 INFO - PROCESS | 1952 | 12:28:54 INFO - PROCESS | 1952 | ------- Summary: start ------- 12:28:54 INFO - PROCESS | 1952 | Number of tests: 6 12:28:54 INFO - PROCESS | 1952 | 12:28:54 INFO - PROCESS | 1952 | [#0] dojo.html Cycles:165 Average:5605.86 Median:5785.33 stddev:634.47 (11.0%) stddev-sans-first:610.07 12:28:54 INFO - PROCESS | 1952 | Values: 3299.4 3375.4 3377.2 3384.4 3433.0 4541.8 5483.4 5492.7 5494.8 5509.1 5453.2 5498.9 5509.7 5519.6 5525.9 4989.0 5033.6 5082.6 5102.4 5128.2 5016.4 5087.3 5102.9 5106.7 5121.5 5072.3 5112.2 5113.6 5130.0 5148.7 5131.8 5141.5 5145.9 5164.9 5200.7 4927.6 4977.9 5026.1 5099.5 5100.2 5350.3 5384.2 5388.8 5458.4 5505.4 6127.7 6129.8 6130.9 6132.4 6132.8 6054.8 6181.6 6204.5 6210.8 6216.5 5618.9 5653.7 5666.4 5735.7 5748.6 6287.5 6316.8 6326.5 6332.4 6337.2 5877.6 5999.3 6010.7 6020.5 6044.2 6101.9 6130.6 6137.1 6157.1 6166.1 5993.7 6035.7 6045.5 6052.7 6082.1 5958.8 6047.1 6074.5 6090.5 6103.6 4786.8 4824.5 4825.0 4830.3 4831.7 4286.5 4289.0 4327.8 4353.9 4382.5 5566.5 5591.6 5608.1 5618.1 5638.2 6120.0 6144.6 6156.8 6158.9 6164.7 5446.4 5504.6 5568.8 5577.1 5596.6 5248.6 5266.9 5267.7 5269.4 5274.2 6220.1 6274.0 6287.2 6305.8 6332.7 6169.2 6204.4 6209.7 6210.6 6228.1 5337.4 6251.2 6288.6 6290.7 6305.2 5867.8 5936.7 5961.9 5972.4 6088.8 5138.0 6012.7 6034.9 6066.6 6105.0 5875.5 5910.9 5911.7 5931.3 5944.2 5921.9 5928.2 5930.0 5934.2 5938.8 5283.3 5655.9 5773.2 5782.3 5808.2 5951.0 5968.1 5971.9 5999.1 6001.3 5763.6 5785.3 5809.2 5819.0 5819.2 12:28:54 INFO - PROCESS | 1952 | 12:28:54 INFO - PROCESS | 1952 | [#1] ext.html Cycles:180 Average:58269.62 Median:3343.02 stddev:122527.64 (3665.2%) stddev-sans-first:122794.58 12:28:54 INFO - PROCESS | 1952 | Values: 496.1 511.8 529.1 533.1 538.8 27696.9 31145.9 37247.8 38126.8 38231.6 40782.2 41250.0 41288.0 41319.0 41322.8 9304.4 9535.8 9538.8 9556.5 9570.6 8487.4 8672.0 11421.7 11594.4 11613.4 11337.7 11437.8 11446.9 11451.0 11545.4 8560.9 11347.4 11446.4 11453.4 11592.4 11314.5 11429.0 11433.6 11454.2 11470.3 37791.8 38024.4 38169.6 38237.0 38308.8 3205.7 3341.2 3383.7 3505.6 3691.2 27235.7 28756.7 29193.9 29228.0 29228.5 2735.9 2743.2 2759.4 2893.5 2896.1 351300.5 353373.0 354134.0 354135.0 354517.0 36003.3 36583.3 58933.7 59440.4 59448.7 35358.5 35607.6 35759.9 35795.8 35859.5 12794.3 12807.0 15285.4 15452.5 15468.0 12169.8 12434.9 13018.9 13049.5 13097.7 6888.2 7994.6 7999.4 8091.3 8108.2 3169.9 3205.1 3344.8 3401.7 3439.8 1012.6 1026.0 1057.9 1117.3 1119.3 24585.8 28823.3 29836.9 30072.7 30266.7 795.5 1066.6 1103.8 1104.5 1141.1 14230.4 14406.0 14820.1 14927.0 15561.4 480527.0 482568.0 486329.0 489897.0 491195.0 60708.4 61331.7 61333.1 61385.7 61434.0 1340.2 1455.8 1487.7 1493.1 1493.7 28087.9 28348.0 28791.6 29501.4 29833.3 48769.0 49453.5 49613.8 49653.0 49680.3 519003.0 522500.0 523234.0 523349.0 524221.0 22465.0 25447.5 25717.6 25963.7 25988.0 32763.5 39510.4 40025.8 41119.4 41670.6 33064.8 33380.0 34556.7 34703.0 35211.6 29047.0 33641.5 33901.7 34453.8 34605.3 34563.1 34924.7 35714.1 35718.0 35789.5 30534.7 31286.0 31291.8 31336.8 31406.8 23858.9 23958.6 24311.3 24315.4 24354.6 12:28:54 INFO - PROCESS | 1952 | 12:28:54 INFO - PROCESS | 1952 | [#2] jquery.html Cycles:180 Average:35775.99 Median:2234.07 stddev:105055.09 (4702.4%) stddev-sans-first:105316.23 12:28:54 INFO - PROCESS | 1952 | Values: 405.5 409.3 409.4 412.4 421.9 18896.6 19747.1 20439.4 20446.3 20462.4 19343.4 19780.9 20106.8 20179.2 20235.7 5661.9 5727.9 5799.4 5845.4 5920.4 5822.6 5852.9 5862.6 5881.7 5882.4 5576.2 5736.4 5798.1 5828.0 5846.9 5680.2 5777.0 5781.3 5800.4 5829.0 5016.8 5099.3 5103.6 5105.2 5111.4 24703.0 25035.7 25404.9 25449.9 25460.2 2210.9 2257.3 2278.6 2321.5 2354.8 14310.0 14553.7 14597.3 15186.9 15221.3 1682.2 1734.5 1738.0 1771.3 1780.3 92576.5 113579.0 113897.4 114887.9 117222.4 10452.3 10673.4 11061.2 11095.2 11205.9 13899.4 13990.6 14019.0 14025.0 14028.4 7282.9 8716.3 8828.8 8873.6 8917.4 8934.2 8972.5 8975.6 9034.6 9074.5 6180.6 6598.2 6598.3 6603.6 6616.9 1754.3 1874.5 1906.8 1910.0 1911.8 746.7 921.9 976.5 992.3 1028.1 14584.3 16224.0 20197.9 20546.6 20963.6 970.9 975.0 984.3 999.2 1034.5 10351.0 11111.4 11173.5 11188.7 11199.0 611237.0 624035.0 624895.0 640905.0 653523.0 130617.0 135541.0 138012.0 138106.0 140589.3 1027.3 1036.9 1061.7 1064.0 1066.0 17480.7 17619.4 17728.9 17847.4 17964.7 47886.3 50371.7 50989.7 52204.7 52604.2 71905.6 72299.0 73492.2 76469.0 77212.2 7706.6 7808.7 8142.2 8166.0 8207.0 12116.9 12249.0 12268.4 12507.9 12574.9 10490.6 11014.0 11043.5 11055.8 11077.6 10205.9 10279.1 10503.5 10739.6 11161.2 10175.9 10808.7 11024.5 11104.3 11215.0 7741.0 7903.8 8021.2 8141.4 8167.2 9516.5 10714.7 10763.6 10806.1 11018.6 12:28:54 INFO - PROCESS | 1952 | 12:28:54 INFO - PROCESS | 1952 | [#3] mootools.html Cycles:180 Average:5505.50 Median:4819.81 stddev:3062.09 (63.5%) stddev-sans-first:3055.31 12:28:54 INFO - PROCESS | 1952 | Values: 1423.7 1572.8 1592.8 1824.8 1944.6 7704.2 8349.9 8502.7 8530.8 8554.4 6842.5 8496.2 8502.4 8509.2 8520.5 2107.0 2332.3 2377.2 2389.7 2410.6 2408.3 2419.9 2421.7 2428.3 2432.7 1987.7 2309.2 2366.8 2371.6 2378.4 2097.5 2339.0 2365.0 2377.7 2391.9 2361.5 2402.9 2403.1 2404.0 2409.1 8404.5 8454.2 8471.4 8475.0 8481.8 5050.4 5184.9 5190.1 5225.3 5225.9 2918.8 3331.8 3400.2 3492.4 3521.0 1047.3 1051.2 1057.7 1058.4 1061.7 11368.6 11398.9 11405.8 11425.9 11462.1 4499.5 4548.6 4688.1 4693.0 4693.7 9680.4 9724.5 9746.0 9756.0 9766.8 5213.4 5224.4 5242.4 5252.0 5267.6 4297.6 4397.7 4537.7 4733.3 4807.5 3842.1 3844.8 3898.8 3936.0 3946.6 1297.7 1298.4 1299.5 1310.1 1316.6 847.8 880.0 920.1 925.5 934.7 5660.3 5673.3 5725.9 5801.2 5832.5 460.8 464.0 467.2 468.4 479.0 3773.1 3784.0 3841.8 3865.5 3932.8 8345.9 10099.4 10133.7 10301.9 10375.5 10085.7 10098.6 10211.8 10222.4 10240.3 7891.5 7922.5 7967.2 8187.8 8188.5 4753.5 5721.2 5756.4 5817.8 5946.6 9129.6 9259.6 9380.7 9400.3 9527.4 9719.7 9744.0 9820.4 9827.2 9837.2 6595.5 6610.8 7117.4 7242.5 7382.0 7913.9 7985.8 8046.5 8047.4 8052.1 7067.3 7180.3 7212.1 7311.0 7346.9 7037.8 7076.0 7080.2 7096.5 7214.0 6753.0 6832.2 6855.2 6862.5 6863.6 4733.8 4734.7 4750.8 4868.7 4970.5 4722.4 4765.5 4817.4 4822.2 4877.7 12:28:54 INFO - PROCESS | 1952 | 12:28:54 INFO - PROCESS | 1952 | [#4] prototype.html Cycles:180 Average:5852.92 Median:6610.67 stddev:1891.38 (28.6%) stddev-sans-first:1850.52 12:28:54 INFO - PROCESS | 1952 | Values: 318.8 326.5 335.5 337.4 339.9 5909.1 5966.4 6595.8 6663.4 6675.5 6440.5 6585.3 6596.6 6602.2 6619.1 4977.5 4982.1 5000.3 5005.5 5011.9 4990.4 4992.7 5010.6 5014.8 5018.9 4683.9 4744.8 4943.6 4952.6 4955.4 4842.4 4855.1 4858.3 4862.9 4891.9 3940.6 4135.0 4166.4 4167.4 4172.5 5622.6 6420.3 6423.5 6424.5 6433.5 5797.7 5809.0 5810.2 5814.6 5814.6 6792.5 7069.4 7079.2 7083.9 7093.6 4549.7 4591.4 4595.0 4602.0 4607.5 7477.0 7550.6 7574.5 7588.5 7614.4 4577.3 4704.7 4706.4 4708.6 4711.6 4735.4 4756.4 4758.1 4767.3 4775.1 5670.5 5682.3 5684.6 5693.6 5701.2 5918.8 7173.9 7195.9 7209.1 7215.2 1528.5 1643.8 1645.6 1647.2 1648.3 957.2 968.0 985.0 1002.2 1037.9 6362.8 6673.4 6809.5 6847.1 6852.5 7482.4 7535.9 7555.0 7555.7 7578.9 6779.1 6781.3 6788.8 6793.0 6823.7 3931.3 3938.5 3961.9 3972.5 3986.6 7607.8 7901.6 7932.8 7933.6 7937.8 7709.2 7748.5 7755.1 7760.1 7760.9 6443.8 7905.8 7908.4 7912.0 7931.0 6009.5 7150.4 7438.0 7445.5 7457.4 7348.4 7362.5 7367.5 7368.8 7388.6 7896.2 7902.6 7907.1 7927.3 7930.8 4674.6 4678.7 4684.7 4684.9 4710.3 7152.6 7419.4 7423.5 7432.2 7438.8 6182.8 6579.4 6987.0 7134.6 7191.1 5799.7 7051.4 7171.4 7223.0 7245.2 7028.3 7066.5 7068.7 7072.9 7089.3 7210.6 7212.7 7213.9 7214.8 7226.5 6760.2 7064.2 7069.5 7079.8 7084.5 12:28:54 INFO - PROCESS | 1952 | 12:28:54 INFO - PROCESS | 1952 | [#5] yui.html Cycles:180 Average:17006.00 Median:63653.02 stddev:23264.27 (36.5%) stddev-sans-first:23297.28 12:28:54 INFO - PROCESS | 1952 | Values: 689.8 691.4 713.6 761.2 778.7 14579.3 14846.6 14901.8 14956.3 15040.5 13625.1 13905.9 13910.7 13934.7 13947.2 704.6 756.5 897.8 944.1 945.8 930.9 955.3 955.6 958.3 959.8 824.0 973.2 992.5 995.6 998.3 779.9 784.6 786.4 847.0 989.3 585.4 716.5 730.0 730.6 733.3 14671.9 14725.8 14746.6 14750.6 14765.3 12006.4 12159.8 12290.9 12433.6 12481.3 9986.1 10167.1 10245.4 10613.0 11136.5 3418.5 3474.9 3480.7 3496.0 3712.2 70952.0 71934.6 72013.6 72340.6 83436.0 9298.6 9377.6 9422.0 9456.3 9731.4 20289.4 20410.1 20417.4 20462.5 20535.2 7252.1 7406.4 7500.9 7557.4 7575.4 4109.7 4117.6 4118.5 4123.4 4135.9 2934.2 3449.5 3453.8 3516.8 3523.8 621.8 622.2 640.4 648.6 649.3 482.6 512.0 580.8 598.7 631.6 7767.2 7885.5 7937.3 8111.2 8161.0 484.3 575.8 592.1 602.2 604.5 3776.7 3795.7 3825.3 3887.1 3958.9 80803.6 83929.2 83968.0 84550.2 84649.6 68121.3 69188.0 69875.7 70017.6 70196.6 63388.4 63917.7 64343.0 64433.7 64560.7 7538.8 7619.8 7646.9 7766.0 7860.6 45057.5 45637.8 45788.8 46227.8 47488.8 58274.5 61674.0 62286.1 63379.4 63946.7 9617.5 9743.6 9808.2 10000.0 10230.2 10518.1 10643.6 10809.6 10856.5 11623.6 8620.4 8857.6 10230.2 10322.7 11837.0 10948.0 11067.9 11233.9 11266.0 11338.5 10749.1 10928.3 11073.5 11117.6 11542.1 9446.2 9550.6 9556.7 9590.3 9679.3 9313.3 9562.4 9619.7 9634.0 9681.5 12:28:54 INFO - PROCESS | 1952 | -------- Summary: end -------- 12:28:54 INFO - PROCESS | 1952 | 12:28:54 INFO - TEST-INFO | 1952: exit 0 12:28:54 INFO - TEST-OK | dromaeo_css | took 1115253ms 12:28:54 INFO - TEST-START | kraken 12:28:54 INFO - operating with platform_type : mac_ 12:28:54 INFO - Initialising browser for kraken test... 12:28:54 INFO - TEST-INFO | started process 1955 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/tmpnu4H8h/profile http://localhost:49562/getInfo.html) 12:28:57 INFO - PROCESS | 1955 | __metrics Screen width/height:1600/1200 12:28:57 INFO - PROCESS | 1955 | colorDepth:24 12:28:57 INFO - PROCESS | 1955 | Browser inner width/height: 1024/689 12:28:57 INFO - PROCESS | 1955 | __metrics 12:28:57 INFO - PROCESS | 1955 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:28:57 ERROR - PROCESS | 1955 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 12:28:58 INFO - TEST-INFO | 1955: exit 0 12:28:58 INFO - Browser initialized. 12:28:58 INFO - Running cycle 1/1 for kraken test... 12:28:58 INFO - TEST-INFO | started process 1957 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/tmpnu4H8h/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:29:10 INFO - PROCESS | 1957 | RSS: Main: 210923520 12:29:10 INFO - PROCESS | 1957 | 12:29:12 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:49562/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49562/tests/kraken/driver.html?testName=audio-beat-detection) 12:29:13 INFO - PROCESS | 1957 | RSS: Main: 271900672 12:29:13 INFO - PROCESS | 1957 | 12:29:16 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:49562/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49562/tests/kraken/driver.html?testName=audio-dft) 12:29:17 INFO - PROCESS | 1957 | RSS: Main: 286240768 12:29:17 INFO - PROCESS | 1957 | 12:29:20 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:49562/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49562/tests/kraken/driver.html?testName=audio-fft) 12:29:21 INFO - PROCESS | 1957 | RSS: Main: 374013952 12:29:21 INFO - PROCESS | 1957 | 12:29:23 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:49562/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49562/tests/kraken/driver.html?testName=audio-oscillator) 12:29:23 INFO - PROCESS | 1957 | RSS: Main: 280092672 12:29:23 INFO - PROCESS | 1957 | 12:29:25 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:49562/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49562/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:29:26 INFO - PROCESS | 1957 | RSS: Main: 280100864 12:29:26 INFO - PROCESS | 1957 | 12:29:29 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:49562/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49562/tests/kraken/driver.html?testName=imaging-darkroom) 12:29:29 INFO - PROCESS | 1957 | RSS: Main: 318189568 12:29:29 INFO - PROCESS | 1957 | 12:29:32 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:49562/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49562/tests/kraken/driver.html?testName=imaging-desaturate) 12:29:33 INFO - PROCESS | 1957 | RSS: Main: 318541824 12:29:33 INFO - PROCESS | 1957 | 12:29:36 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:49562/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49562/tests/kraken/driver.html?testName=json-parse-financial) 12:29:36 INFO - PROCESS | 1957 | RSS: Main: 319291392 12:29:36 INFO - PROCESS | 1957 | 12:29:38 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:49562/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49562/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:29:39 INFO - PROCESS | 1957 | RSS: Main: 309616640 12:29:39 INFO - PROCESS | 1957 | 12:29:40 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:49562/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49562/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:29:41 INFO - PROCESS | 1957 | RSS: Main: 316854272 12:29:41 INFO - PROCESS | 1957 | 12:29:43 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:49562/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49562/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:29:44 INFO - PROCESS | 1957 | RSS: Main: 319332352 12:29:44 INFO - PROCESS | 1957 | 12:29:47 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:49562/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49562/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:29:47 INFO - PROCESS | 1957 | RSS: Main: 324153344 12:29:47 INFO - PROCESS | 1957 | 12:29:50 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:49562/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49562/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:29:51 INFO - PROCESS | 1957 | RSS: Main: 322375680 12:29:51 INFO - PROCESS | 1957 | 12:29:53 INFO - PROCESS | 1957 | Cycle 1(1): loaded http://localhost:49562/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49562/tests/kraken/driver.html?testName=ai-astar) 12:29:53 INFO - PROCESS | 1957 | RSS: Main: 387158016 12:29:53 INFO - PROCESS | 1957 | 12:29:53 INFO - PROCESS | 1957 | __start_tp_report 12:29:53 INFO - PROCESS | 1957 | _x_x_mozilla_page_load 12:29:53 INFO - PROCESS | 1957 | _x_x_mozilla_page_load_details 12:29:53 INFO - PROCESS | 1957 | |i|pagename|runs| 12:29:53 INFO - PROCESS | 1957 | |0;ai-astar;114;114;119;115;113;112;112;114;113;135 12:29:53 INFO - PROCESS | 1957 | |1;audio-beat-detection;159;126;142;172;137;144;106;174;198;149 12:29:53 INFO - PROCESS | 1957 | |2;audio-dft;184;185;185;185;184;183;183;184;185;184 12:29:53 INFO - PROCESS | 1957 | |3;audio-fft;75;78;75;157;56;55;55;61;135;56 12:29:53 INFO - PROCESS | 1957 | |4;audio-oscillator;97;94;142;84;123;128;137;80;77;124 12:29:53 INFO - PROCESS | 1957 | |5;imaging-gaussian-blur;90;130;88;84;88;84;88;84;88;86 12:29:53 INFO - PROCESS | 1957 | |6;imaging-darkroom;92;93;93;93;92;93;93;97;93;93 12:29:53 INFO - PROCESS | 1957 | |7;imaging-desaturate;86;87;85;83;86;83;83;84;86;85 12:29:53 INFO - PROCESS | 1957 | |8;json-parse-financial;61;64;64;63;64;66;64;83;58;59 12:29:53 INFO - PROCESS | 1957 | |9;json-stringify-tinderbox;62;69;95;101;72;78;73;68;69;71 12:29:53 INFO - PROCESS | 1957 | |10;stanford-crypto-aes;81;81;81;80;80;81;81;81;83;85 12:29:53 INFO - PROCESS | 1957 | |11;stanford-crypto-ccm;131;130;128;127;125;129;127;130;147;157 12:29:53 INFO - PROCESS | 1957 | |12;stanford-crypto-pbkdf2;166;166;166;163;164;167;164;165;165;218 12:29:53 INFO - PROCESS | 1957 | |13;stanford-crypto-sha256-iterative;69;68;73;89;101;96;57;80;90;65 12:29:53 INFO - PROCESS | 1957 | __end_tp_report 12:29:53 INFO - PROCESS | 1957 | __start_cc_report 12:29:53 INFO - PROCESS | 1957 | _x_x_mozilla_cycle_collect,2033 12:29:53 INFO - PROCESS | 1957 | __end_cc_report 12:29:53 INFO - PROCESS | 1957 | __startTimestamp1464290993490__endTimestamp 12:29:53 INFO - PROCESS | 1957 | 12:29:53 INFO - PROCESS | 1957 | ------- Summary: start ------- 12:29:53 INFO - PROCESS | 1957 | Number of tests: 14 12:29:53 INFO - PROCESS | 1957 | 12:29:53 INFO - PROCESS | 1957 | [#0] ai-astar Cycles:10 Average:116.10 Median:114.00 stddev:6.94 (6.1%) stddev-sans-first:7.31 12:29:53 INFO - PROCESS | 1957 | Values: 114.0 114.0 119.0 115.0 113.0 112.0 112.0 114.0 113.0 135.0 12:29:53 INFO - PROCESS | 1957 | 12:29:53 INFO - PROCESS | 1957 | [#1] audio-beat-detection Cycles:10 Average:150.70 Median:154.00 stddev:26.29 (17.1%) stddev-sans-first:27.72 12:29:53 INFO - PROCESS | 1957 | Values: 159.0 126.0 142.0 172.0 137.0 144.0 106.0 174.0 198.0 149.0 12:29:53 INFO - PROCESS | 1957 | 12:29:53 INFO - PROCESS | 1957 | [#2] audio-dft Cycles:10 Average:184.20 Median:184.50 stddev:0.79 (0.4%) stddev-sans-first:0.83 12:29:53 INFO - PROCESS | 1957 | Values: 184.0 185.0 185.0 185.0 184.0 183.0 183.0 184.0 185.0 184.0 12:29:53 INFO - PROCESS | 1957 | 12:29:53 INFO - PROCESS | 1957 | [#3] audio-fft Cycles:10 Average:80.30 Median:58.50 stddev:36.16 (61.8%) stddev-sans-first:38.31 12:29:53 INFO - PROCESS | 1957 | Values: 75.0 78.0 75.0 157.0 56.0 55.0 55.0 61.0 135.0 56.0 12:29:53 INFO - PROCESS | 1957 | 12:29:53 INFO - PROCESS | 1957 | [#4] audio-oscillator Cycles:10 Average:108.60 Median:78.50 stddev:24.75 (31.5%) stddev-sans-first:25.89 12:29:53 INFO - PROCESS | 1957 | Values: 97.0 94.0 142.0 84.0 123.0 128.0 137.0 80.0 77.0 124.0 12:29:53 INFO - PROCESS | 1957 | 12:29:53 INFO - PROCESS | 1957 | [#5] imaging-gaussian-blur Cycles:10 Average:91.00 Median:88.00 stddev:13.86 (15.8%) stddev-sans-first:14.70 12:29:53 INFO - PROCESS | 1957 | Values: 90.0 130.0 88.0 84.0 88.0 84.0 88.0 84.0 88.0 86.0 12:29:53 INFO - PROCESS | 1957 | 12:29:53 INFO - PROCESS | 1957 | [#6] imaging-darkroom Cycles:10 Average:93.20 Median:93.00 stddev:1.40 (1.5%) stddev-sans-first:1.41 12:29:53 INFO - PROCESS | 1957 | Values: 92.0 93.0 93.0 93.0 92.0 93.0 93.0 97.0 93.0 93.0 12:29:53 INFO - PROCESS | 1957 | 12:29:53 INFO - PROCESS | 1957 | [#7] imaging-desaturate Cycles:10 Average:84.80 Median:85.50 stddev:1.48 (1.7%) stddev-sans-first:1.50 12:29:53 INFO - PROCESS | 1957 | Values: 86.0 87.0 85.0 83.0 86.0 83.0 83.0 84.0 86.0 85.0 12:29:53 INFO - PROCESS | 1957 | 12:29:53 INFO - PROCESS | 1957 | [#8] json-parse-financial Cycles:10 Average:64.60 Median:64.00 stddev:6.93 (10.8%) stddev-sans-first:7.23 12:29:53 INFO - PROCESS | 1957 | Values: 61.0 64.0 64.0 63.0 64.0 66.0 64.0 83.0 58.0 59.0 12:29:53 INFO - PROCESS | 1957 | 12:29:53 INFO - PROCESS | 1957 | [#9] json-stringify-tinderbox Cycles:10 Average:75.80 Median:71.50 stddev:12.46 (17.4%) stddev-sans-first:12.18 12:29:53 INFO - PROCESS | 1957 | Values: 62.0 69.0 95.0 101.0 72.0 78.0 73.0 68.0 69.0 71.0 12:29:53 INFO - PROCESS | 1957 | 12:29:53 INFO - PROCESS | 1957 | [#10] stanford-crypto-aes Cycles:10 Average:81.40 Median:81.00 stddev:1.51 (1.9%) stddev-sans-first:1.59 12:29:53 INFO - PROCESS | 1957 | Values: 81.0 81.0 81.0 80.0 80.0 81.0 81.0 81.0 83.0 85.0 12:29:53 INFO - PROCESS | 1957 | 12:29:53 INFO - PROCESS | 1957 | [#11] stanford-crypto-ccm Cycles:10 Average:133.10 Median:130.00 stddev:10.39 (8.0%) stddev-sans-first:10.99 12:29:53 INFO - PROCESS | 1957 | Values: 131.0 130.0 128.0 127.0 125.0 129.0 127.0 130.0 147.0 157.0 12:29:53 INFO - PROCESS | 1957 | 12:29:53 INFO - PROCESS | 1957 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.40 Median:166.00 stddev:16.77 (10.1%) stddev-sans-first:17.71 12:29:53 INFO - PROCESS | 1957 | Values: 166.0 166.0 166.0 163.0 164.0 167.0 164.0 165.0 165.0 218.0 12:29:53 INFO - PROCESS | 1957 | 12:29:53 INFO - PROCESS | 1957 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.80 Median:76.50 stddev:14.65 (19.2%) stddev-sans-first:15.10 12:29:53 INFO - PROCESS | 1957 | Values: 69.0 68.0 73.0 89.0 101.0 96.0 57.0 80.0 90.0 65.0 12:29:53 INFO - PROCESS | 1957 | -------- Summary: end -------- 12:29:53 INFO - PROCESS | 1957 | 12:29:54 INFO - TEST-INFO | 1957: exit 0 12:29:54 INFO - TEST-OK | kraken | took 59572ms 12:29:54 INFO - SUITE-END | took 1174s 12:29:54 INFO - Completed test suite (00:19:34) 12:29:54 INFO - javascript benchmark 12:29:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3299.356625458035, 3375.409303371909, 3377.1725927147836, 3384.4415671414217, 3432.9828350858256, 4541.772911354431, 5483.424240454755, 5492.725363731817, 5494.835154945353, 5509.118541033436, 5453.23654688344, 5498.945010549894, 5509.669419834812, 5519.613627046108, 5525.889482210363, 4988.950110498885, 5033.647644664884, 5082.644214904958, 5102.438731739506, 5128.230765385203, 5016.423111342187, 5087.338645976018, 5102.851858331865, 5106.66806657566, 5121.462246464123, 5072.26452164436, 5112.182050871843, 5113.565346945514, 5130.025295193901, 5148.665336753118, 5131.846044618686, 5141.485851414866, 5145.922811157836, 5164.948350516484, 5200.6619569727845, 4927.6304277179, 4977.925331120036, 5026.070177017227, 5099.515546023122, 5100.209467532502, 5350.2777125088005, 5384.1923711443205, 5388.757505912244, 5458.372287186945, 5505.421930697259, 6127.662978536178, 6129.84675383119, 6130.877382452326, 6132.417420345105, 6132.846678833021, 6054.788082417138, 6181.567290289681, 6204.503639708858, 6210.782622608187, 6216.533759967975, 5618.943810561883, 5653.745581448804, 5666.433356664383, 5735.684537350486, 5748.626339287933, 6287.49700023997, 6316.810495685137, 6326.5255105867245, 6332.398422149889, 6337.2395312562785, 5877.617954832922, 5999.310079340845, 6010.699465026732, 6020.488258497989, 6044.214252147192, 6101.938980610137, 6130.601510901777, 6137.070562331796, 6157.076438534255, 6166.075088736726, 5993.700314984233, 6035.698215089315, 6045.516358691294, 6052.727627256696, 6082.118092876508, 5958.76164953397, 6047.092936059538, 6074.51403887688, 6090.543209259324, 6103.633781973096, 4786.7845946932475, 4824.517548245218, 4825.010872771144, 4830.275458681155, 4831.710097394168, 4286.5284818669925, 4289.034967132433, 4327.805248763813, 4353.869383918487, 4382.474103107584, 5566.471185237396, 5591.552675785927, 5608.074667679787, 5618.073017952074, 5638.23883775685, 6119.969400152971, 6144.56988010835, 6156.78451254204, 6158.876822463526, 6164.660943648053, 5446.428125046813, 5504.614676972574, 5568.832935011957, 5577.05190117681, 5596.608237423423, 5248.606354523426, 5266.947330526722, 5267.6576022559175, 5269.367675878919, 5274.208868669731, 6220.098085777628, 6274.0, 6287.245530536365, 6305.842353941188, 6332.651704156131, 6169.2288463942, 6204.3795620439405, 6209.689515524296, 6210.596311239755, 6228.128062071223, 5337.359516858002, 6251.156093927261, 6288.622682639057, 6290.716917738621, 6305.180326557518, 5867.765289388377, 5936.703164841827, 5961.850953726192, 5972.432618901197, 6088.817335479943, 5138.0, 6012.699365031818, 6034.8793024138395, 6066.605670631395, 6105.0, 5875.50058245054, 5910.940890591039, 5911.733971971357, 5931.3178984417345, 5944.227250457413, 5921.940780592139, 5928.229330187048, 5930.0, 5934.1988831508925, 5938.792142275, 5283.260343551982, 5655.858603534944, 5773.191753154646, 5782.277215348082, 5808.157817116579, 5951.047832346804, 5968.134620480093, 5971.850703732442, 5999.070144127667, 6001.279846418283, 5763.567732420001, 5785.334686511105, 5809.157672137432, 5818.981678206382, 5819.243498345188], "value": 5565.9034053780515, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [496.1342457411817, 511.80295586199304, 529.1216580476407, 533.1443033930541, 538.7710223155158, 27696.861515692417, 31145.909893153745, 37247.81376093119, 38126.80936595313, 38231.617683823155, 40782.18435631286, 41250.0, 41288.0, 41319.0, 41322.79338603303, 9304.441733495978, 9535.80928381432, 9538.761530961712, 9556.522173891313, 9570.617175312978, 8487.363447741443, 8672.046074931752, 11421.714457138596, 11594.362310072942, 11613.361265130414, 11337.659870203906, 11437.771244575104, 11446.885531144666, 11450.969412752851, 11545.422728863565, 8560.871586926201, 11347.43262836859, 11446.427678616035, 11453.427328633577, 11592.362420066893, 11314.490847911864, 11429.0, 11433.599824006204, 11454.198206125575, 11470.311781293149, 37791.811040944616, 38024.42963355552, 38169.61830381688, 38237.0, 38308.80845595782, 3205.7114859662743, 3341.198112452994, 3383.678550537695, 3505.5793304803333, 3691.2063906260014, 27235.727642723516, 28756.712432875822, 29193.85403072971, 29227.97702080438, 29228.538573071473, 2735.9452810943867, 2743.2044707034934, 2759.351552385188, 2893.5225687761495, 2896.0877323642985, 351300.4869951293, 353373.0, 354133.99999999744, 354135.0, 354517.0, 36003.27993440169, 36583.26833463316, 58933.705331473924, 59440.40559594435, 59448.70275648594, 35358.46962295516, 35607.64392356095, 35759.92720218398, 35795.82102089473, 35859.4621080684, 12794.296313702836, 12807.0, 15285.388584456497, 15452.459163929208, 15467.99458035224, 12169.756604868031, 12434.943029842383, 13018.869811301955, 13049.478020879249, 13097.672558185932, 6888.242293347727, 7994.6002699864775, 7999.400044996649, 8091.312238459679, 8108.2297181767635, 3169.9380707462797, 3205.1346136543, 3344.8494817733263, 3401.693847553732, 3439.7764145330475, 1012.5747186181821, 1026.0, 1057.8677665291839, 1117.3407689463256, 1119.339589642115, 24585.84581065675, 28823.2794180143, 29836.850815746217, 30072.699273007427, 30266.697333026827, 795.4869109424388, 1066.6160182334509, 1103.7902798468426, 1104.4808939798281, 1141.1042331769522, 14230.43078276878, 14406.0, 14820.110793352218, 14927.029743066665, 15561.377544898078, 480527.0, 482568.0, 486329.0, 489897.0, 491195.0, 60708.392916071156, 61331.693341533006, 61333.08000379998, 61385.693071534355, 61434.0, 1340.222670850904, 1455.7670772676322, 1487.7173337065922, 1493.0817547208605, 1493.6788590453104, 28087.859560702065, 28347.974718765363, 28791.568126478538, 29501.409971800014, 29833.25416864596, 48769.0, 49453.50546494633, 49613.75193124011, 49653.0, 49680.25479617736, 519003.0, 522500.0, 523234.0, 523349.0, 524221.0, 22465.0, 25447.491050179262, 25717.614235786852, 25963.74036259613, 25987.96048158128, 32763.508547372287, 39510.40734388929, 40025.79987100046, 41119.38320925248, 41670.58329416788, 33064.842730504315, 33380.0, 34556.65443345635, 34702.9589112327, 35211.64788352186, 29046.98335558245, 33641.495377569845, 33901.66098339084, 34453.82773086119, 34605.30789384249, 34563.13592160216, 34924.65075349316, 35714.10714732153, 35718.0, 35789.46315805213, 30534.694653053186, 31285.95600676783, 31291.84354078215, 31336.843315783277, 31406.842965785025, 23858.880705596363, 23958.640620391056, 24311.27066188017, 24315.39211519726, 24354.634680479452], "value": 17977.737305902014, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [405.53161098930735, 409.3287009304739, 409.38796499233644, 412.4205491284746, 421.919835231306, 18896.62206755864, 19747.1113799879, 20439.386818395455, 20446.2843800467, 20462.386128416136, 19343.4196974091, 19780.912049837254, 20106.798932010675, 20179.19283228669, 20235.696464553042, 5661.886762264752, 5727.914081288784, 5799.420057994188, 5845.415458454163, 5920.407959204067, 5822.563307751935, 5852.853678658046, 5862.618929769573, 5881.735321910524, 5882.411758824126, 5576.247206627114, 5736.426357364272, 5798.072308430652, 5828.0, 5846.88306233875, 5680.233168522259, 5776.971115144397, 5781.306243250795, 5800.361960184377, 5828.97085514574, 5016.849494515171, 5099.33708617881, 5103.642745007851, 5105.234214867763, 5111.361079865017, 24703.0, 25035.749642503706, 25404.85678144488, 25449.87275063613, 25460.236192914243, 2210.856294340863, 2257.2776711452343, 2278.6126358518927, 2321.4892723600783, 2354.8233882458885, 14310.0, 14553.708925821531, 14597.343119559646, 15186.924065379713, 15221.31504082319, 1682.167327173045, 1734.453647101159, 1737.9913100434417, 1771.3268957796083, 1780.2789870102629, 92576.53711731466, 113579.0, 113897.43051284773, 114887.85112148855, 117222.41388793087, 10452.320599161183, 10673.41296228715, 11061.170412219115, 11095.167862410352, 11205.943970280096, 13899.444022239199, 13990.580282591538, 14019.0, 14025.0, 14028.438862445388, 7282.927170728331, 8716.259117974916, 8828.77928051791, 8873.64505419776, 8917.42036767608, 8934.195922367018, 8972.461652300883, 8975.640974361084, 9034.63861445548, 9074.546272686339, 6180.6291622502795, 6598.241202261705, 6598.274189839114, 6603.636799976048, 6616.86766264672, 1754.2807448946126, 1874.5313671582105, 1906.8283854453166, 1909.990450047741, 1911.8470522358177, 746.7199800074972, 921.8525035994209, 976.4873441443299, 992.335135459244, 1028.0541901450683, 14584.270786460635, 16224.0, 20197.899010504854, 20546.589068218553, 20963.580728385346, 970.8834939807268, 974.9902500975041, 984.2519685039422, 999.1956475037664, 1034.5154703001217, 10351.0, 11111.44442777874, 11173.497192626352, 11188.66434006981, 11199.040167970572, 611237.0, 624035.0, 624895.0, 640905.0, 653523.0, 130617.0, 135541.0, 138012.0, 138106.0, 140589.29705351408, 1027.2552399510444, 1036.896310368957, 1061.7345663584103, 1063.9946800266105, 1066.024587502442, 17480.65038699244, 17619.392727727103, 17728.936263824213, 17847.37534186297, 17964.73052904181, 47886.28170577374, 50371.74814126053, 50989.725256867394, 52204.73897630639, 52604.21093683521, 71905.6404717994, 72299.0, 73492.16269593303, 76469.0, 77212.2278777205, 7706.614669266402, 7808.726694565663, 8142.226488483585, 8165.959170204349, 8207.0, 12116.939415303219, 12248.95883849847, 12268.447919843808, 12507.93746031264, 12574.931130853614, 10490.580376785148, 11014.0, 11043.503042363274, 11055.778884422105, 11077.612283570359, 10205.897941020496, 10279.126274266771, 10503.474826258504, 10739.624113156002, 11161.218714689894, 10175.898241017496, 10808.729781755519, 11024.50389732448, 11104.333739975627, 11215.046721028477, 7741.0, 7903.762887113396, 8021.197880211932, 8141.43009989295, 8167.224113709188, 9516.524173791144, 10714.73213169677, 10763.623273185402, 10806.081483073713, 11018.614348498084], "value": 9638.064271885505, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1423.679672073784, 1572.7562227854683, 1592.773564355446, 1824.7536582561359, 1944.5527528668397, 7704.2295770423, 8349.874751878719, 8502.659893604265, 8530.786730331736, 8554.358423118276, 6842.452603791698, 8496.235338819504, 8502.404831661786, 8509.19162679546, 8520.531370774605, 2106.9894650526803, 2332.2536788227776, 2377.2155188787747, 2389.701287339087, 2410.6143017117265, 2408.254015838512, 2419.8669073200967, 2421.7336093029753, 2428.3200703802963, 2432.6959130108735, 1987.7316562264054, 2309.2033248529315, 2366.8343215974887, 2371.6086845670443, 2378.369732021016, 2097.4861159015986, 2339.0, 2365.0, 2377.679013333204, 2391.9162829301067, 2361.456864921078, 2402.939926501834, 2403.098837935774, 2403.975960240393, 2409.132712223598, 8404.537750423724, 8454.196851299117, 8471.407001509897, 8475.025372082227, 8481.787955301104, 5050.444451110375, 5184.948150518522, 5190.06578815813, 5225.268462415265, 5225.895482090337, 2918.8324670131906, 3331.816750078745, 3400.1669590950128, 3492.4412094065083, 3521.0, 1047.314009323894, 1051.1958351860833, 1057.7461409261798, 1058.4284486377346, 1061.7451811565245, 11368.60209892658, 11398.886011139866, 11405.771884562262, 11425.942870285679, 11462.140339474572, 4499.470180138768, 4548.56788605082, 4688.132695451352, 4693.0, 4693.741844198602, 9680.419174849532, 9724.465154416575, 9746.0, 9755.951220243853, 9766.75583110428, 5213.374395072616, 5224.373075230997, 5242.423333433319, 5251.973740131275, 5267.578593712494, 4297.59172878576, 4397.73613583186, 4537.682362234678, 4733.313669517901, 4807.495213002654, 3842.1355195080882, 3844.8077596120083, 3898.785566793797, 3936.0, 3946.6053394660303, 1297.7274772297735, 1298.4226310773108, 1299.5451591942744, 1310.0960337367376, 1316.5918565244804, 847.8092429203504, 879.9736007919772, 920.1216870889101, 925.4678559828126, 934.6962237272913, 5660.349059858087, 5673.3475650299915, 5725.942740572625, 5801.187833703285, 5832.475077243069, 460.82488654311146, 464.03017693021746, 467.17543535659587, 468.4425533614987, 478.9664723469324, 3773.113318370181, 3783.9621603784162, 3841.750286231386, 3865.4781604483037, 3932.7640341579595, 8345.916540834636, 10099.394036357695, 10133.746656333504, 10301.948490257651, 10375.481225938673, 10085.697429076992, 10098.596056157672, 10211.795764084825, 10222.437765922945, 10240.334378265388, 7891.526508409399, 7922.524648521107, 7967.163447838009, 8187.83624327522, 8188.467749596257, 4753.5008824072975, 5721.170430287668, 5756.395578464369, 5817.825465236049, 5946.643201407929, 9129.634814607341, 9259.583318750807, 9380.67167649129, 9400.34197606161, 9527.428354298487, 9719.708408747749, 9744.025597440199, 9820.361676491006, 9827.164691001344, 9837.163841073589, 6595.505337099833, 6610.8016759497295, 7117.430605551647, 7242.529235599669, 7382.0, 7913.881291780513, 7985.8402831944195, 8046.47697899658, 8047.396445266511, 8052.114267430461, 7067.328603782633, 7180.281971802778, 7212.098487689039, 7311.049563556704, 7346.926530734838, 7037.824054398477, 7076.0, 7080.185778635317, 7096.503244772816, 7214.0, 6752.966235168793, 6832.214295356098, 6855.177378714586, 6862.4853136014, 6863.622500762306, 4733.763311834464, 4734.668573199843, 4750.8337208197545, 4868.659193856396, 4970.527799859007, 4722.433308002925, 4765.499622539722, 4817.445993710628, 4822.180229361087, 4877.731724755173], "value": 4390.828205753342, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [318.8007495315429, 326.5428400239664, 335.5151805640849, 337.399429016351, 339.92521645238037, 5909.109085092767, 5966.433188847052, 6595.835104122393, 6663.366980136885, 6675.499337549691, 6440.484761219103, 6585.341465853412, 6596.637184954826, 6602.207735071786, 6619.139511863463, 4977.452480227172, 4982.128127577659, 5000.3499545059185, 5005.499450054984, 5011.874703132415, 4990.401151861782, 4992.67547609406, 5010.599152067844, 5014.8244811431605, 5018.874528136807, 4683.929741053887, 4744.810207591692, 4943.604511639078, 4952.603791696673, 4955.355803745526, 4842.370491836074, 4855.126077306101, 4858.295547145644, 4862.878428039292, 4891.902161956777, 3940.601086614239, 4135.028268356934, 4166.395872598457, 4167.416561681367, 4172.499300083979, 5622.606417550773, 6420.293767685551, 6423.454006409461, 6424.486041116699, 6433.453156481704, 5797.652140871562, 5809.0, 5810.157527158581, 5814.563907706896, 5814.592978491507, 6792.45660347176, 7069.36375726182, 7079.185893622196, 7083.858322833566, 7093.574385536833, 4549.727016378994, 4591.357209990604, 4594.954050459486, 4602.0, 4607.493175750665, 7477.0, 7550.583813179785, 7574.5076570022775, 7588.506747061479, 7614.428917831129, 4577.3362862384765, 4704.670673052853, 4706.364640773451, 4708.6468514860835, 4711.646626503026, 4735.431748190239, 4756.381670382828, 4758.119747846659, 4767.308740232646, 4775.140474714515, 5670.489655930986, 5682.289713785777, 5684.6020778545935, 5693.572982026365, 5701.2018317435595, 5918.816236752667, 7173.89239161413, 7195.89206161908, 7209.134903811471, 7215.242399547972, 1528.4879565345734, 1643.8356164383465, 1645.5803770038563, 1647.2422685564534, 1648.3489021836547, 957.1861322793407, 967.9661211857551, 984.9655262065793, 1002.2082554781787, 1037.9377237365782, 6362.777302794472, 6673.366030227121, 6809.489288303298, 6847.075644787989, 6852.5203235773015, 7482.4388170887405, 7535.924640753522, 7554.962225188949, 7555.697772089165, 7578.886316705254, 6779.084823548757, 6781.287964763727, 6788.8302792429595, 6793.0, 6823.69293381799, 3931.292367373843, 3938.5076865391825, 3961.8613348532663, 3972.5431575368625, 3986.621270979252, 7607.771766847003, 7901.644426000844, 7932.84134317322, 7933.56365399897, 7937.8412431752195, 7709.2290770922455, 7748.496347737379, 7755.069391673035, 7760.068791744914, 7760.9223907761325, 6443.80789553934, 7905.802354941287, 7908.3673306136525, 7912.0, 7931.0172456885775, 6009.549283803644, 7150.42796576283, 7437.962810185914, 7445.478816483011, 7457.440691948017, 7348.375388091858, 7362.5214361066355, 7367.5211111277595, 7368.815779605338, 7388.630568471663, 7896.170902055202, 7902.589710138233, 7907.130215676156, 7927.286544211049, 7930.841383172419, 4674.6026587740425, 4678.742669153229, 4684.718916864999, 4684.882877928079, 4710.340552322746, 7152.642367881689, 7419.406447484081, 7423.517471364344, 7432.219616940143, 7438.776836694691, 6182.814515564541, 6579.374959378852, 6987.0, 7134.607596582237, 7191.137063552198, 5799.681017544076, 7051.435885129074, 7171.354578087997, 7222.98878157048, 7245.203027666847, 7028.332308430691, 7066.470014748812, 7068.717251310096, 7072.939059140964, 7089.326513981164, 7210.603416812126, 7212.675429605785, 7213.855722885408, 7214.783556493314, 7226.458015648742, 6760.1549806274215, 7064.222935476991, 7069.540479868998, 7079.752208672672, 7084.468664850054], "value": 5210.613762510156, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [689.8103021669041, 691.3673988300703, 713.5825542057893, 761.1589193940692, 778.6612823421812, 14579.271036448175, 14846.628834279121, 14901.776473352907, 14956.32696528656, 15040.473583424582, 13625.1143675661, 13905.860941390609, 13910.652233694163, 13934.721305573883, 13947.232902190375, 704.623026680726, 756.5007095317092, 897.8294124116397, 944.0842382888587, 945.7777422305787, 930.906909309067, 955.3312681123207, 955.5890966884238, 958.3100167879115, 959.7840485890689, 824.0, 973.1728031173517, 992.4789485520088, 995.5569771451707, 998.2962011781676, 779.9181085986003, 784.6390660296271, 786.3944762532818, 846.9788255293605, 989.2877128467528, 585.4292065236403, 716.4662326566689, 730.0217708270942, 730.5835673666013, 733.2777214443769, 14671.926640366837, 14725.779113313309, 14746.631334216623, 14750.55748327552, 14765.261736913111, 12006.399680015964, 12159.817602735966, 12290.938545307306, 12433.564825231164, 12481.31352775597, 9986.101250887383, 10167.08496235335, 10245.385276883337, 10613.0, 11136.498857551429, 3418.453047512411, 3474.947875781865, 3480.6519348065244, 3496.0, 3712.1647629283466, 70952.0, 71934.64032679856, 72013.6399318, 72340.63829680817, 83436.0, 9298.581563829644, 9377.578008989476, 9422.0, 9456.290778191664, 9731.41611503312, 20289.39131826018, 20410.081546330155, 20417.387478375673, 20462.488437789176, 20535.17859285612, 7252.093488313961, 7406.444516661164, 7500.924990750132, 7557.395408367317, 7575.356094731899, 4109.671226301889, 4117.56765539614, 4118.464599602033, 4123.422720819088, 4135.855245066408, 2934.207763903734, 3449.534312867781, 3453.8100404477996, 3516.7889926604485, 3523.7533372663665, 621.8041316985135, 622.2470810319564, 640.3916279534461, 648.5557393185588, 649.2955143669077, 482.63319876893814, 511.9846404607793, 580.8286555466148, 598.7396530303648, 631.6462780842743, 7767.2232776723, 7885.5268683878, 7937.285644292041, 8111.1888811118415, 8160.959195204105, 484.3146947069927, 575.8128608202351, 592.1473078766562, 602.1780269931512, 604.4680681000779, 3776.6978641709147, 3795.6773674237998, 3825.2923209206388, 3887.125396785757, 3958.9010274742786, 80803.59598201972, 83929.16070839214, 83968.0, 84550.15449845546, 84649.57675211584, 68121.3187868125, 69188.0, 69875.65062174656, 70017.64991175011, 70196.59606807835, 63388.366116339166, 63917.68041159764, 64343.0, 64433.67783161148, 64560.677196614655, 7538.802660753801, 7619.809504762426, 7646.885296720665, 7765.961170194113, 7860.606969651609, 45057.549424505334, 45637.77181114206, 45788.771056144506, 46227.76886115548, 47488.762556187, 58274.543136421926, 61674.0, 62286.06570901349, 63379.36620633735, 63946.68026659837, 9617.519124043909, 9743.56153973087, 9808.215342772704, 9999.950000249952, 10230.181585472996, 10518.105960993402, 10643.627473038407, 10809.621663241749, 10856.45717714127, 11623.59317423886, 8620.353473489387, 8857.556393568484, 10230.181585472996, 10322.690319290434, 11836.993855522376, 10948.0, 11067.944660276647, 11233.887661123284, 11266.0, 11338.546458141582, 10749.14006879432, 10928.289661171999, 11073.50169242402, 11117.610883619358, 11542.13433992437, 9446.24430045581, 9550.617975280911, 9556.713298601053, 9590.328676992543, 9679.322447428613, 9313.25493960468, 9562.378445401027, 9619.711408657751, 9634.0, 9681.515924203903], "value": 6650.517002740697, "unit": "score"}], "name": "dromaeo_css", "value": 7262.696170368724, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 119.0, 115.0, 113.0, 112.0, 112.0, 114.0, 113.0, 135.0], "value": 116.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 126.0, 142.0, 172.0, 137.0, 144.0, 106.0, 174.0, 198.0, 149.0], "value": 150.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 185.0, 185.0, 185.0, 184.0, 183.0, 183.0, 184.0, 185.0, 184.0], "value": 184.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 78.0, 75.0, 157.0, 56.0, 55.0, 55.0, 61.0, 135.0, 56.0], "value": 80.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 94.0, 142.0, 84.0, 123.0, 128.0, 137.0, 80.0, 77.0, 124.0], "value": 108.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 130.0, 88.0, 84.0, 88.0, 84.0, 88.0, 84.0, 88.0, 86.0], "value": 91.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 93.0, 93.0, 92.0, 93.0, 93.0, 97.0, 93.0, 93.0], "value": 93.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 85.0, 83.0, 86.0, 83.0, 83.0, 84.0, 86.0, 85.0], "value": 84.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 64.0, 64.0, 63.0, 64.0, 66.0, 64.0, 83.0, 58.0, 59.0], "value": 64.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 69.0, 95.0, 101.0, 72.0, 78.0, 73.0, 68.0, 69.0, 71.0], "value": 75.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 81.0, 80.0, 80.0, 81.0, 81.0, 81.0, 83.0, 85.0], "value": 81.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 130.0, 128.0, 127.0, 125.0, 129.0, 127.0, 130.0, 147.0, 157.0], "value": 133.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 166.0, 166.0, 163.0, 164.0, 167.0, 164.0, 165.0, 165.0, 218.0], "value": 170.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 73.0, 89.0, 101.0, 96.0, 57.0, 80.0, 90.0, 65.0], "value": 78.8, "unit": "score"}], "name": "kraken", "value": 1513.0, "alertThreshold": 2.0}]} 12:29:54 INFO - Return code: 0 12:29:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 12:29:54 ERROR - # TBPL SUCCESS # 12:29:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:29:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:29:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:29:54 INFO - 2016-05-26 12:29:54.837 screenresolution[1961:13629] starting screenresolution argv=screenresolution get 12:29:54 INFO - 2016-05-26 12:29:54.840 screenresolution[1961:13629] Display 0: 1600x1200x32@60 12:29:54 INFO - 2016-05-26 12:29:54.846 screenresolution[1962:13632] starting screenresolution argv=screenresolution list 12:29:54 INFO - Available Modes on Display 0 12:29:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:29:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:29:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:29:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:29:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:29:55 INFO - Intel Iris: 12:29:55 INFO - Chipset Model: Intel Iris 12:29:55 INFO - Type: GPU 12:29:55 INFO - Bus: Built-In 12:29:55 INFO - VRAM (Dynamic, Max): 1536 MB 12:29:55 INFO - Vendor: Intel (0x8086) 12:29:55 INFO - Device ID: 0x0a2e 12:29:55 INFO - Revision ID: 0x0009 12:29:55 INFO - Displays: 12:29:55 INFO - Display: 12:29:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:29:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:29:55 INFO - Display Serial Number: 1600x1200 60 12:29:55 INFO - Main Display: Yes 12:29:55 INFO - Mirror: Off 12:29:55 INFO - Online: Yes 12:29:55 INFO - Rotation: Supported 12:29:55 INFO - Return code: 0 12:29:55 INFO - Running post-action listener: _resource_record_post_action 12:29:55 INFO - Running post-run listener: _resource_record_post_run 12:29:56 INFO - Total resource usage - Wall time: 1208s; CPU: 52.0%; Read bytes: 376832; Write bytes: 651823616; Read time: 4; Write time: 1811 12:29:56 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 346618368; Write bytes: 346982400; Read time: 26443; Write time: 698 12:29:56 INFO - run-tests - Wall time: 1177s; CPU: 52.0%; Read bytes: 376832; Write bytes: 294834688; Read time: 4; Write time: 1076 12:29:56 INFO - Running post-run listener: _upload_blobber_files 12:29:56 INFO - Blob upload gear active. 12:29:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:29:56 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 12:29:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:29:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:29:56 INFO - (blobuploader) - INFO - Open directory for files ... 12:29:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:29:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:29:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:29:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:29:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:29:57 INFO - (blobuploader) - INFO - Done attempting. 12:29:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:29:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:29:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:29:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:29:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:29:58 INFO - (blobuploader) - INFO - Done attempting. 12:29:58 INFO - (blobuploader) - INFO - Iteration through files over. 12:29:58 INFO - Return code: 0 12:29:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:29:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:29:58 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c9d9ebae92157a6a735810f587f179aa06773fec8f0a2589c92d190ba4f29551dc42fbcf4ae09022b09149b8a3bcdf367d0b350f9729d3aa678293c46aa645bb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c04d81e5189b36e4c40583822217f3f75694e8300de3755bd02857b8cecc513329f593c8e3300b0e6c424f7793abd2f97bc541a8aa6f062d346a7cb1218b78c5"} 12:29:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:29:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:29:58 INFO - Contents: 12:29:58 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c9d9ebae92157a6a735810f587f179aa06773fec8f0a2589c92d190ba4f29551dc42fbcf4ae09022b09149b8a3bcdf367d0b350f9729d3aa678293c46aa645bb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c04d81e5189b36e4c40583822217f3f75694e8300de3755bd02857b8cecc513329f593c8e3300b0e6c424f7793abd2f97bc541a8aa6f062d346a7cb1218b78c5"} 12:29:58 INFO - Running post-run listener: copy_logs_to_upload_dir 12:29:58 INFO - Copying logs to upload dir... 12:29:58 INFO - mkdir: /builds/slave/test/build/upload/logs 12:29:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.251309 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-05-26 12:29:58.537944) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-26 12:29:58.541951) ========= 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.TihuaNKSQ9/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.1ZKS69xVfE/Listeners TMPDIR=/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c9d9ebae92157a6a735810f587f179aa06773fec8f0a2589c92d190ba4f29551dc42fbcf4ae09022b09149b8a3bcdf367d0b350f9729d3aa678293c46aa645bb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c04d81e5189b36e4c40583822217f3f75694e8300de3755bd02857b8cecc513329f593c8e3300b0e6c424f7793abd2f97bc541a8aa6f062d346a7cb1218b78c5"} build_url:https://queue.taskcluster.net/v1/task/Tt_p062KSx-6sp0njp_UEA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.009779 build_url: 'https://queue.taskcluster.net/v1/task/Tt_p062KSx-6sp0njp_UEA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c9d9ebae92157a6a735810f587f179aa06773fec8f0a2589c92d190ba4f29551dc42fbcf4ae09022b09149b8a3bcdf367d0b350f9729d3aa678293c46aa645bb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c04d81e5189b36e4c40583822217f3f75694e8300de3755bd02857b8cecc513329f593c8e3300b0e6c424f7793abd2f97bc541a8aa6f062d346a7cb1218b78c5"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-26 12:29:58.569125) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 12:29:58.569469) ========= 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.TihuaNKSQ9/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.1ZKS69xVfE/Listeners TMPDIR=/var/folders/t7/_pz7xln100vdkv4w4jlh985400000w/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.004704 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-26 12:29:58.621978) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-26 12:29:58.622739) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-26 12:29:58.623663) ========= ========= Total master_lag: 0.43 =========