builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0171 starttime: 1462476685.45 results: success (0) buildid: 20160505103448 builduid: dd35c8d42e6745669048b66fbea75044 revision: cb425f373a802dc57c8fefffad95487334467ec2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-05 12:31:25.446366) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-05 12:31:25.446837) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-05 12:31:25.447157) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-05 12:31:25.467185) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 12:31:25.467890) ========= 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.AobOWwxtbW/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.rPx3C0vPJE/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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-05 12:31:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.9M=0.001s 2016-05-05 12:31:25 (14.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.131778 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 12:31:25.622267) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 12:31:25.622970) ========= 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.AobOWwxtbW/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.rPx3C0vPJE/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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.026305 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 12:31:25.675212) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 12:31:25.676057) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev cb425f373a802dc57c8fefffad95487334467ec2 --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 cb425f373a802dc57c8fefffad95487334467ec2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.AobOWwxtbW/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.rPx3C0vPJE/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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-05 12:31:25,753 truncating revision to first 12 chars 2016-05-05 12:31:25,753 Setting DEBUG logging. 2016-05-05 12:31:25,753 attempt 1/10 2016-05-05 12:31:25,753 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/cb425f373a80?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-05 12:31:26,009 unpacking tar archive at: mozilla-aurora-cb425f373a80/testing/mozharness/ program finished with exit code 0 elapsedTime=0.503140 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 12:31:26.196076) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-05 12:31:26.196439) ========= script_repo_revision: cb425f373a802dc57c8fefffad95487334467ec2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-05 12:31:26.196900) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-05 12:31:26.197203) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-05 12:31:26.210882) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-05-05 12:31:26.211384) ========= /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.AobOWwxtbW/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.rPx3C0vPJE/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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:31:26 INFO - MultiFileLogger online at 20160505 12:31:26 in /builds/slave/test 12:31:26 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:31:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:31:26 INFO - {'append_to_log': False, 12:31:26 INFO - 'base_work_dir': '/builds/slave/test', 12:31:26 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 12:31:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:31:26 INFO - 'branch': 'Mozilla-Aurora', 12:31:26 INFO - 'buildbot_json_path': 'buildprops.json', 12:31:26 INFO - 'config_files': ('talos/mac_config.py',), 12:31:26 INFO - 'default_actions': ('clobber', 12:31:26 INFO - 'read-buildbot-config', 12:31:26 INFO - 'download-and-extract', 12:31:26 INFO - 'populate-webroot', 12:31:26 INFO - 'create-virtualenv', 12:31:26 INFO - 'install', 12:31:26 INFO - 'run-tests'), 12:31:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:31:26 INFO - 'download_minidump_stackwalk': True, 12:31:26 INFO - 'download_symbols': 'ondemand', 12:31:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:31:26 INFO - 'tooltool.py': '/tools/tooltool.py', 12:31:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:31:26 INFO - '/tools/misc-python/virtualenv.py')}, 12:31:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:31:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:31:26 INFO - 'installer_path': 'installer.exe', 12:31:26 INFO - 'log_level': 'info', 12:31:26 INFO - 'log_name': 'talos', 12:31:26 INFO - 'log_to_console': True, 12:31:26 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:31:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:31:26 INFO - 'opt_config_files': (), 12:31:26 INFO - 'pip_index': False, 12:31:26 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:31:26 INFO - 'cmd': ('bash', 12:31:26 INFO - '-c', 12:31:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:31:26 INFO - 'enabled': True, 12:31:26 INFO - 'halt_on_failure': False, 12:31:26 INFO - 'name': 'check_screen_resolution'},), 12:31:26 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:31:26 INFO - 'cmd': ('bash', 12:31:26 INFO - '-c', 12:31:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:31:26 INFO - 'enabled': True, 12:31:26 INFO - 'halt_on_failure': False, 12:31:26 INFO - 'name': 'check_screen_resolution'},), 12:31:26 INFO - 'run_cmd_checks_enabled': True, 12:31:26 INFO - 'sps_profile': False, 12:31:26 INFO - 'sps_profile_interval': 0, 12:31:26 INFO - 'suite': 'dromaeojs', 12:31:26 INFO - 'system_bits': '32', 12:31:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:31:26 INFO - 'title': 't-yosemite-r7-0171', 12:31:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:31:26 INFO - 'use_talos_json': True, 12:31:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:31:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:31:26 INFO - 'work_dir': 'build'} 12:31:26 INFO - ##### 12:31:26 INFO - ##### Running clobber step. 12:31:26 INFO - ##### 12:31:26 INFO - Running pre-action listener: _resource_record_pre_action 12:31:26 INFO - Running main action method: clobber 12:31:26 INFO - rmtree: /builds/slave/test/build 12:31:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:31:28 INFO - Running post-action listener: _resource_record_post_action 12:31:28 INFO - ##### 12:31:28 INFO - ##### Running read-buildbot-config step. 12:31:28 INFO - ##### 12:31:28 INFO - Running pre-action listener: _resource_record_pre_action 12:31:28 INFO - Running main action method: read_buildbot_config 12:31:28 INFO - Using buildbot properties: 12:31:28 INFO - { 12:31:28 INFO - "project": "", 12:31:28 INFO - "product": "firefox", 12:31:28 INFO - "script_repo_revision": "production", 12:31:28 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 12:31:28 INFO - "repository": "", 12:31:28 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 12:31:28 INFO - "buildid": "20160505103448", 12:31:28 INFO - "pgo_build": "False", 12:31:28 INFO - "basedir": "/builds/slave/test", 12:31:28 INFO - "buildnumber": 0, 12:31:28 INFO - "slavename": "t-yosemite-r7-0171", 12:31:28 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 12:31:28 INFO - "platform": "yosemite_r7", 12:31:28 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 12:31:28 INFO - "branch": "mozilla-aurora", 12:31:28 INFO - "repo_path": "releases/mozilla-aurora", 12:31:28 INFO - "revision": "cb425f373a802dc57c8fefffad95487334467ec2", 12:31:28 INFO - "stage_platform": "macosx64", 12:31:28 INFO - "builduid": "dd35c8d42e6745669048b66fbea75044", 12:31:28 INFO - "slavebuilddir": "test" 12:31:28 INFO - } 12:31:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 12:31:28 INFO - Running post-action listener: _resource_record_post_action 12:31:28 INFO - ##### 12:31:28 INFO - ##### Running download-and-extract step. 12:31:28 INFO - ##### 12:31:28 INFO - Running pre-action listener: _resource_record_pre_action 12:31:28 INFO - Running main action method: download_and_extract 12:31:28 INFO - mkdir: /builds/slave/test/build/tests 12:31: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:31:28 INFO - https://queue.taskcluster.net/v1/task/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 12:31:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 12:31:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 12:31:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F775xk7JQDyh51wKVQaHbg/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:31:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F775xk7JQDyh51wKVQaHbg/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:31:29 INFO - Downloaded 1199 bytes. 12:31:29 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 12:31:29 INFO - Using the following test package requirements: 12:31:29 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 12:31:29 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:31:29 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 12:31:29 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:31:29 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 12:31:29 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:31:29 INFO - u'jsshell-mac.zip'], 12:31:29 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:31:29 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 12:31:29 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 12:31:29 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:31:29 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 12:31:29 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:31:29 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 12:31:29 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:31:29 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 12:31:29 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:31:29 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 12:31:29 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 12:31:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:31:29 INFO - https://queue.taskcluster.net/v1/task/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:31:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 12:31:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 12:31:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F775xk7JQDyh51wKVQaHbg/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:31:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F775xk7JQDyh51wKVQaHbg/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:31:30 INFO - Downloaded 18114337 bytes. 12:31:30 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:31:30 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:31:33 INFO - Return code: 0 12:31:33 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:31: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:31:33 INFO - https://queue.taskcluster.net/v1/task/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:31:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 12:31:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 12:31:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F775xk7JQDyh51wKVQaHbg/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:31:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F775xk7JQDyh51wKVQaHbg/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:31:35 INFO - Downloaded 18114337 bytes. 12:31:35 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:31:35 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:31:38 INFO - Return code: 0 12:31: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:31:38 INFO - https://queue.taskcluster.net/v1/task/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 12:31:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 12:31:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 12:31:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F775xk7JQDyh51wKVQaHbg/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:31:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F775xk7JQDyh51wKVQaHbg/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:31:39 INFO - Downloaded 10840145 bytes. 12:31:39 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:31:39 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:31:40 INFO - Return code: 0 12:31:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:31:40 INFO - https://queue.taskcluster.net/v1/task/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 12:31:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 12:31:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 12:31:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 12:31:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/F775xk7JQDyh51wKVQaHbg/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:31:43 INFO - Downloaded 114100985 bytes. 12:31:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 12:31:43 INFO - mkdir: /builds/slave/test/properties 12:31:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:31:43 INFO - Writing to file /builds/slave/test/properties/build_url 12:31:43 INFO - Contents: 12:31:43 INFO - build_url:https://queue.taskcluster.net/v1/task/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 12:31:44 INFO - Running post-action listener: _resource_record_post_action 12:31:44 INFO - Running post-action listener: set_extra_try_arguments 12:31:44 INFO - ##### 12:31:44 INFO - ##### Running populate-webroot step. 12:31:44 INFO - ##### 12:31:44 INFO - Running pre-action listener: _resource_record_pre_action 12:31:44 INFO - Running main action method: populate_webroot 12:31:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:31:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:31:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:31:44 INFO - '--tppagecycles', 12:31:44 INFO - '7'], 12:31:44 INFO - 'tests': ['tsvgm']}}, 12:31:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:31:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:31:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:31:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:31:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:31:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:31:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:31:44 INFO - 'talos_options': ['--disable-e10s'], 12:31:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:31:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:31:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:31:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:31:44 INFO - 'talos_options': ['--disable-e10s'], 12:31:44 INFO - 'tests': ['damp', 'tps']}, 12:31:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:31:44 INFO - 'tests': ['damp', 'tps']}, 12:31:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:31:44 INFO - 'tests': ['dromaeo_dom']}, 12:31:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:31:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:31:44 INFO - 'tests': ['a11yr', 12:31:44 INFO - 'ts_paint', 12:31:44 INFO - 'tpaint', 12:31:44 INFO - 'sessionrestore', 12:31:44 INFO - 'sessionrestore_no_auto_restore', 12:31:44 INFO - 'tabpaint']}, 12:31:44 INFO - 'other-e10s': {'tests': ['a11yr', 12:31:44 INFO - 'ts_paint', 12:31:44 INFO - 'tpaint', 12:31:44 INFO - 'sessionrestore', 12:31:44 INFO - 'sessionrestore_no_auto_restore', 12:31:44 INFO - 'tabpaint']}, 12:31:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:31:44 INFO - 'ts_paint', 12:31:44 INFO - 'tpaint', 12:31:44 INFO - 'sessionrestore', 12:31:44 INFO - 'sessionrestore_no_auto_restore', 12:31:44 INFO - 'tabpaint']}, 12:31:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:31:44 INFO - 'ts_paint', 12:31:44 INFO - 'tpaint', 12:31:44 INFO - 'sessionrestore', 12:31:44 INFO - 'sessionrestore_no_auto_restore', 12:31:44 INFO - 'tabpaint']}, 12:31:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:31:44 INFO - 'tests': ['a11yr', 12:31:44 INFO - 'ts_paint', 12:31:44 INFO - 'tpaint', 12:31:44 INFO - 'sessionrestore', 12:31:44 INFO - 'sessionrestore_no_auto_restore', 12:31:44 INFO - 'tabpaint']}, 12:31:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:31:44 INFO - 'tests': ['a11yr', 12:31:44 INFO - 'ts_paint', 12:31:44 INFO - 'tpaint', 12:31:44 INFO - 'sessionrestore', 12:31:44 INFO - 'sessionrestore_no_auto_restore', 12:31:44 INFO - 'tabpaint']}, 12:31:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:31:44 INFO - 'tests': ['tsvgx', 12:31:44 INFO - 'tsvgr_opacity', 12:31:44 INFO - 'tart', 12:31:44 INFO - 'tscrollx', 12:31:44 INFO - 'cart']}, 12:31:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:31:44 INFO - 'tsvgr_opacity', 12:31:44 INFO - 'tart', 12:31:44 INFO - 'tscrollx', 12:31:44 INFO - 'cart']}, 12:31:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:31:44 INFO - 'talos_options': ['--disable-e10s'], 12:31:44 INFO - 'tests': ['tp5o']}, 12:31:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:31:44 INFO - 'tests': ['tp5o']}, 12:31:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:31:44 INFO - 'talos_options': ['--disable-e10s', 12:31:44 INFO - '--xperf_path', 12:31:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:31:44 INFO - 'tests': ['tp5n']}, 12:31:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:31:44 INFO - 'talos_options': ['--xperf_path', 12:31:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:31:44 INFO - 'tests': ['tp5n']}}, 12:31:44 INFO - 'talos.zip': {'path': '', 12:31:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:31:44 INFO - Running post-action listener: _resource_record_post_action 12:31:44 INFO - ##### 12:31:44 INFO - ##### Running create-virtualenv step. 12:31:44 INFO - ##### 12:31:44 INFO - Running pre-action listener: _resource_record_pre_action 12:31:44 INFO - Running main action method: create_virtualenv 12:31:44 INFO - Creating virtualenv /builds/slave/test/build/venv 12:31:44 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:31:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:31:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:31:44 INFO - Using real prefix '/tools/python27' 12:31:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:31:45 INFO - Installing distribute.............................................................................................................................................................................................done. 12:31:47 INFO - Installing pip.................done. 12:31:47 INFO - Return code: 0 12:31:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:31:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:31:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:31:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:31:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:31:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:31:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:31:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10947fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10948f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98584a7890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10931ee00>, '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 0x109187db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109319b20>, '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.AobOWwxtbW/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.rPx3C0vPJE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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:31:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:31:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:31:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AobOWwxtbW/Render', 12:31:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:31:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:31:47 INFO - 'HOME': '/Users/cltbld', 12:31:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:31:47 INFO - 'LOGNAME': 'cltbld', 12:31:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:31:47 INFO - 'MOZ_NO_REMOTE': '1', 12:31:47 INFO - 'NO_EM_RESTART': '1', 12:31:47 INFO - 'PAGER': '/bin/cat', 12:31:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:31:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:31:47 INFO - 'PWD': '/builds/slave/test', 12:31:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:31:47 INFO - 'SHELL': '/bin/bash', 12:31:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rPx3C0vPJE/Listeners', 12:31:47 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 12:31:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:31:47 INFO - 'USER': 'cltbld', 12:31:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:31:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:31:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:31:47 INFO - 'XPC_FLAGS': '0x0', 12:31:47 INFO - 'XPC_SERVICE_NAME': '0', 12:31:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:31:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:31:48 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:31:48 INFO - Cleaning up... 12:31:48 INFO - Return code: 0 12:31:48 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 12:31:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:31:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:31:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:31:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:31:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:31:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:31:48 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 0x10947fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10948f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98584a7890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10931ee00>, '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 0x109187db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109319b20>, '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.AobOWwxtbW/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.rPx3C0vPJE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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:31:48 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:31:48 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:31:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AobOWwxtbW/Render', 12:31:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:31:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:31:48 INFO - 'HOME': '/Users/cltbld', 12:31:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:31:48 INFO - 'LOGNAME': 'cltbld', 12:31:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:31:48 INFO - 'MOZ_NO_REMOTE': '1', 12:31:48 INFO - 'NO_EM_RESTART': '1', 12:31:48 INFO - 'PAGER': '/bin/cat', 12:31:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:31:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:31:48 INFO - 'PWD': '/builds/slave/test', 12:31:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:31:48 INFO - 'SHELL': '/bin/bash', 12:31:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rPx3C0vPJE/Listeners', 12:31:48 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 12:31:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:31:48 INFO - 'USER': 'cltbld', 12:31:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:31:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:31:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:31:48 INFO - 'XPC_FLAGS': '0x0', 12:31:48 INFO - 'XPC_SERVICE_NAME': '0', 12:31:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:31:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:31:48 INFO - Downloading/unpacking psutil>=0.7.1 12:31:48 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:31:48 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:31:48 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:31:48 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:31:48 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:31:48 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:31:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:31:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:31:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:31:51 INFO - Installing collected packages: psutil 12:31:51 INFO - Running setup.py install for psutil 12:31:51 INFO - building 'psutil._psutil_osx' extension 12:31:51 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 12:31:52 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 12:31:52 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 12:31:52 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:31:52 INFO - building 'psutil._psutil_posix' extension 12:31:52 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:31:52 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:31:52 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:31:52 INFO - ^ 12:31:52 INFO - 1 warning generated. 12:31:52 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:31:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:31:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:31:52 INFO - Successfully installed psutil 12:31:52 INFO - Cleaning up... 12:31:52 INFO - Return code: 0 12:31:52 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 12:31:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:31:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:31:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:31:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:31:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:31:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:31:52 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 0x10947fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10948f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98584a7890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10931ee00>, '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 0x109187db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109319b20>, '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.AobOWwxtbW/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.rPx3C0vPJE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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:31:52 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:31:52 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:31:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AobOWwxtbW/Render', 12:31:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:31:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:31:52 INFO - 'HOME': '/Users/cltbld', 12:31:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:31:52 INFO - 'LOGNAME': 'cltbld', 12:31:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:31:52 INFO - 'MOZ_NO_REMOTE': '1', 12:31:52 INFO - 'NO_EM_RESTART': '1', 12:31:52 INFO - 'PAGER': '/bin/cat', 12:31:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:31:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:31:52 INFO - 'PWD': '/builds/slave/test', 12:31:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:31:52 INFO - 'SHELL': '/bin/bash', 12:31:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rPx3C0vPJE/Listeners', 12:31:52 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 12:31:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:31:52 INFO - 'USER': 'cltbld', 12:31:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:31:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:31:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:31:52 INFO - 'XPC_FLAGS': '0x0', 12:31:52 INFO - 'XPC_SERVICE_NAME': '0', 12:31:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:31:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:31:52 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 12:31:52 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:31:52 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:31:52 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:31:52 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:31:52 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:31:52 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:31:55 INFO - Downloading mozsystemmonitor-0.0.tar.gz 12:31:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:31:55 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:31:55 INFO - Installing collected packages: mozsystemmonitor 12:31:55 INFO - Running setup.py install for mozsystemmonitor 12:31:55 INFO - Successfully installed mozsystemmonitor 12:31:55 INFO - Cleaning up... 12:31:55 INFO - Return code: 0 12:31:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:31:55 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:31:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:31:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:31:55 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:31:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:31:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:31:55 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 0x10947fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10948f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98584a7890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10931ee00>, '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 0x109187db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109319b20>, '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.AobOWwxtbW/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.rPx3C0vPJE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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:31:55 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:31:55 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:31:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AobOWwxtbW/Render', 12:31:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:31:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:31:55 INFO - 'HOME': '/Users/cltbld', 12:31:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:31:55 INFO - 'LOGNAME': 'cltbld', 12:31:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:31:55 INFO - 'MOZ_NO_REMOTE': '1', 12:31:55 INFO - 'NO_EM_RESTART': '1', 12:31:55 INFO - 'PAGER': '/bin/cat', 12:31:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:31:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:31:55 INFO - 'PWD': '/builds/slave/test', 12:31:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:31:55 INFO - 'SHELL': '/bin/bash', 12:31:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rPx3C0vPJE/Listeners', 12:31:55 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 12:31:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:31:55 INFO - 'USER': 'cltbld', 12:31:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:31:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:31:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:31:55 INFO - 'XPC_FLAGS': '0x0', 12:31:55 INFO - 'XPC_SERVICE_NAME': '0', 12:31:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:31:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:31:55 INFO - Downloading/unpacking blobuploader==1.2.4 12:31:55 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:31:55 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:31:55 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:31:55 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:31:55 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:31:55 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:31:58 INFO - Downloading blobuploader-1.2.4.tar.gz 12:31:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:31:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:31:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:31:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:31:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:31:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:31:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:31:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:31:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:31:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:31:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:31:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:31:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:31:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:31:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:31:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:31:59 INFO - Downloading docopt-0.6.1.tar.gz 12:31:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:31:59 INFO - Installing collected packages: blobuploader, requests, docopt 12:31:59 INFO - Running setup.py install for blobuploader 12:31:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:31:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:31:59 INFO - Running setup.py install for requests 12:32:00 INFO - Running setup.py install for docopt 12:32:00 INFO - Successfully installed blobuploader requests docopt 12:32:00 INFO - Cleaning up... 12:32:00 INFO - Return code: 0 12:32:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:32:00 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:32:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:32:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:00 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:32:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:32:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:00 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 0x10947fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10948f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98584a7890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10931ee00>, '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 0x109187db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109319b20>, '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.AobOWwxtbW/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.rPx3C0vPJE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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:32:00 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:32:00 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:32:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AobOWwxtbW/Render', 12:32:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:32:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:32:00 INFO - 'HOME': '/Users/cltbld', 12:32:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:32:00 INFO - 'LOGNAME': 'cltbld', 12:32:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:32:00 INFO - 'MOZ_NO_REMOTE': '1', 12:32:00 INFO - 'NO_EM_RESTART': '1', 12:32:00 INFO - 'PAGER': '/bin/cat', 12:32:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:32:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:32:00 INFO - 'PWD': '/builds/slave/test', 12:32:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:32:00 INFO - 'SHELL': '/bin/bash', 12:32:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rPx3C0vPJE/Listeners', 12:32:00 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 12:32:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:32:00 INFO - 'USER': 'cltbld', 12:32:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:32:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:32:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:32:00 INFO - 'XPC_FLAGS': '0x0', 12:32:00 INFO - 'XPC_SERVICE_NAME': '0', 12:32:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:32:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:32:00 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-2KV_D3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:32:00 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-U4e0x4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:32:00 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-WiozJq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:32:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:32:01 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-xjldIC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:32:01 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-MXsz05-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:32:01 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-gWq1bg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:32:01 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-dGWvE8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:32:01 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-mUR1xa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:32:01 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-8EWwfc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:32:01 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-3eRcee-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:32:01 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-T7BJbR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:32:01 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-ihVkFI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:32:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:32:01 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-gN10PV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:32:02 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-LtVtWW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:32:02 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-dN7SSx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:32:02 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-sHvicB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:32:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:32:02 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-WNLomh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:32:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:32:02 INFO - Running setup.py install for manifestparser 12:32:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:32:02 INFO - Running setup.py install for mozcrash 12:32:02 INFO - Running setup.py install for mozdebug 12:32:02 INFO - Running setup.py install for mozdevice 12:32:03 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:32:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:32:03 INFO - Running setup.py install for mozfile 12:32:03 INFO - Running setup.py install for mozhttpd 12:32:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:32:03 INFO - Running setup.py install for mozinfo 12:32:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:32:03 INFO - Running setup.py install for mozInstall 12:32:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:32:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:32:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:32:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:32:03 INFO - Running setup.py install for mozleak 12:32:03 INFO - Running setup.py install for mozlog 12:32:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:32:03 INFO - Running setup.py install for moznetwork 12:32:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:32:04 INFO - Running setup.py install for mozprocess 12:32:04 INFO - Running setup.py install for mozprofile 12:32:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:32:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:32:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:32:04 INFO - Running setup.py install for mozrunner 12:32:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:32:04 INFO - Running setup.py install for mozscreenshot 12:32:04 INFO - Running setup.py install for moztest 12:32:04 INFO - Running setup.py install for mozversion 12:32:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:32:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:32:05 INFO - Cleaning up... 12:32:05 INFO - Return code: 0 12:32:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:32:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:32:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:32:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:32:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:32:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:05 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 0x10947fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10948f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98584a7890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10931ee00>, '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 0x109187db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109319b20>, '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.AobOWwxtbW/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.rPx3C0vPJE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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:32:05 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:32:05 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:32:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AobOWwxtbW/Render', 12:32:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:32:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:32:05 INFO - 'HOME': '/Users/cltbld', 12:32:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:32:05 INFO - 'LOGNAME': 'cltbld', 12:32:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:32:05 INFO - 'MOZ_NO_REMOTE': '1', 12:32:05 INFO - 'NO_EM_RESTART': '1', 12:32:05 INFO - 'PAGER': '/bin/cat', 12:32:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:32:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:32:05 INFO - 'PWD': '/builds/slave/test', 12:32:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:32:05 INFO - 'SHELL': '/bin/bash', 12:32:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rPx3C0vPJE/Listeners', 12:32:05 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 12:32:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:32:05 INFO - 'USER': 'cltbld', 12:32:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:32:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:32:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:32:05 INFO - 'XPC_FLAGS': '0x0', 12:32:05 INFO - 'XPC_SERVICE_NAME': '0', 12:32:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:32:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:32:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:32:05 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-81yxQ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:32:05 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:32:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:32:05 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-C6uhge-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:32:05 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:32:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:32:05 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-VgpNgS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:32:05 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:32:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:32:05 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-lZBLSz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:32:05 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:32:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:32:05 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-1VptQ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:32:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 12:32:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:32:05 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-W8ZwXP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:32:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 12:32:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:32:05 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-LXSQIR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:32:06 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:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:32:06 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-rcLwQ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:32:06 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:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:32:06 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-_HiacN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:32:06 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:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:32:06 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-cy1Ecy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:32:06 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:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:32:06 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-OhpbZO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:32:06 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:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:32:06 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-mbxOmd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:32:06 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:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:32:06 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-_wb4ki-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:32:06 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:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:32:06 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-eqt8S4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:32:06 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:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:32:06 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-ocQKUR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:32:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 12:32:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:32:06 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-pXbhl2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:32:07 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:32:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:32:07 INFO - Running setup.py (path:/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/pip-Pf8TCr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:32:07 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:32:07 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:32:07 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:32:07 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:32:07 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:32:07 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:32:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:32:07 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:32:07 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:32:07 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:32:07 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:32:07 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:32:07 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:32:09 INFO - Downloading blessings-1.6.tar.gz 12:32:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:32:09 INFO - Installing collected packages: blessings 12:32:09 INFO - Running setup.py install for blessings 12:32:09 INFO - Successfully installed blessings 12:32:09 INFO - Cleaning up... 12:32:09 INFO - Return code: 0 12:32:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:32:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:32:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:32:10 INFO - Reading from file tmpfile_stdout 12:32:10 INFO - Current package versions: 12:32:10 INFO - blessings == 1.6 12:32:10 INFO - blobuploader == 1.2.4 12:32:10 INFO - docopt == 0.6.1 12:32:10 INFO - manifestparser == 1.1 12:32:10 INFO - mozInstall == 1.12 12:32:10 INFO - mozcrash == 0.17 12:32:10 INFO - mozdebug == 0.1 12:32:10 INFO - mozdevice == 0.48 12:32:10 INFO - mozfile == 1.2 12:32:10 INFO - mozhttpd == 0.7 12:32:10 INFO - mozinfo == 0.9 12:32:10 INFO - mozleak == 0.1 12:32:10 INFO - mozlog == 3.1 12:32:10 INFO - moznetwork == 0.27 12:32:10 INFO - mozprocess == 0.22 12:32:10 INFO - mozprofile == 0.28 12:32:10 INFO - mozrunner == 6.11 12:32:10 INFO - mozscreenshot == 0.1 12:32:10 INFO - mozsystemmonitor == 0.0 12:32:10 INFO - moztest == 0.7 12:32:10 INFO - mozversion == 1.4 12:32:10 INFO - psutil == 3.1.1 12:32:10 INFO - requests == 1.2.3 12:32:10 INFO - wsgiref == 0.1.2 12:32:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:32:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:32:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:32:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:32:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:32:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:10 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 0x10947fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10948f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98584a7890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10931ee00>, '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 0x109187db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109319b20>, '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.AobOWwxtbW/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.rPx3C0vPJE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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:32:10 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:32:10 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:32:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AobOWwxtbW/Render', 12:32:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:32:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:32:10 INFO - 'HOME': '/Users/cltbld', 12:32:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:32:10 INFO - 'LOGNAME': 'cltbld', 12:32:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:32:10 INFO - 'MOZ_NO_REMOTE': '1', 12:32:10 INFO - 'NO_EM_RESTART': '1', 12:32:10 INFO - 'PAGER': '/bin/cat', 12:32:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:32:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:32:10 INFO - 'PWD': '/builds/slave/test', 12:32:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:32:10 INFO - 'SHELL': '/bin/bash', 12:32:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rPx3C0vPJE/Listeners', 12:32:10 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 12:32:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:32:10 INFO - 'USER': 'cltbld', 12:32:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:32:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:32:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:32:10 INFO - 'XPC_FLAGS': '0x0', 12:32:10 INFO - 'XPC_SERVICE_NAME': '0', 12:32:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:32:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:32:10 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:32:10 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:32:10 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:32:10 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:32:10 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:32:10 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:32:10 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:32:10 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:32:10 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:32:10 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:32:10 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:32:10 INFO - Cleaning up... 12:32:10 INFO - Return code: 0 12:32:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:32:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:32:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:32:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:32:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:32:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10947fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10948f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98584a7890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10931ee00>, '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 0x109187db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109319b20>, '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.AobOWwxtbW/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.rPx3C0vPJE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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:32:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:32:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:32:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AobOWwxtbW/Render', 12:32:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:32:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:32:10 INFO - 'HOME': '/Users/cltbld', 12:32:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:32:10 INFO - 'LOGNAME': 'cltbld', 12:32:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:32:10 INFO - 'MOZ_NO_REMOTE': '1', 12:32:10 INFO - 'NO_EM_RESTART': '1', 12:32:10 INFO - 'PAGER': '/bin/cat', 12:32:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:32:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:32:10 INFO - 'PWD': '/builds/slave/test', 12:32:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:32:10 INFO - 'SHELL': '/bin/bash', 12:32:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rPx3C0vPJE/Listeners', 12:32:10 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 12:32:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:32:10 INFO - 'USER': 'cltbld', 12:32:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:32:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:32:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:32:10 INFO - 'XPC_FLAGS': '0x0', 12:32:10 INFO - 'XPC_SERVICE_NAME': '0', 12:32:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:32:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:32:10 INFO - Downloading/unpacking jsonschema 12:32:10 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:32:10 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:32:10 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:32:10 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:32:10 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:32:10 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:32:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:32:13 INFO - Downloading/unpacking functools32 (from jsonschema) 12:32:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:32:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:32:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:32:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:32:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:32:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:32:13 INFO - Downloading functools32-3.2.3-2.tar.gz 12:32:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:32:13 INFO - warning: no files found matching '*.txt' 12:32:13 INFO - no previously-included directories found matching 'build' 12:32:13 INFO - no previously-included directories found matching 'dist' 12:32:13 INFO - no previously-included directories found matching '.git*' 12:32:13 INFO - Installing collected packages: jsonschema, functools32 12:32:13 INFO - Running setup.py install for functools32 12:32:13 INFO - warning: no files found matching '*.txt' 12:32:13 INFO - no previously-included directories found matching 'build' 12:32:13 INFO - no previously-included directories found matching 'dist' 12:32:13 INFO - no previously-included directories found matching '.git*' 12:32:13 INFO - Successfully installed jsonschema functools32 12:32:13 INFO - Cleaning up... 12:32:13 INFO - Return code: 0 12:32:13 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 12:32:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:32:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:32:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:32:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:32:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:32:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10947fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10948f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98584a7890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10931ee00>, '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 0x109187db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109319b20>, '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.AobOWwxtbW/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.rPx3C0vPJE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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:32:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 12:32:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 12:32:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AobOWwxtbW/Render', 12:32:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:32:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:32:13 INFO - 'HOME': '/Users/cltbld', 12:32:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:32:13 INFO - 'LOGNAME': 'cltbld', 12:32:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:32:13 INFO - 'MOZ_NO_REMOTE': '1', 12:32:13 INFO - 'NO_EM_RESTART': '1', 12:32:13 INFO - 'PAGER': '/bin/cat', 12:32:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:32:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:32:13 INFO - 'PWD': '/builds/slave/test', 12:32:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:32:13 INFO - 'SHELL': '/bin/bash', 12:32:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rPx3C0vPJE/Listeners', 12:32:13 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 12:32:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:32:13 INFO - 'USER': 'cltbld', 12:32:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:32:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:32:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:32:13 INFO - 'XPC_FLAGS': '0x0', 12:32:13 INFO - 'XPC_SERVICE_NAME': '0', 12:32:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:32:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:32:14 INFO - Downloading/unpacking flake8 12:32:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:32:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:32:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:32:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:32:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:32:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:32:16 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 12:32:16 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 12:32: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:32: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:32: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:32: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:32: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:32: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:32:17 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 12:32:17 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 12:32:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:32:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:32:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:32:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:32:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:32:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:32:17 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 12:32:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:32:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:32:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:32:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:32:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:32:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:32:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 12:32:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:32:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:32:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:32:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:32:18 INFO - no previously-included directories found matching 'docs/_build' 12:32:18 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 12:32:18 INFO - Running setup.py install for pep8 12:32:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:32:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:32:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:32:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:32:18 INFO - no previously-included directories found matching 'docs/_build' 12:32:18 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 12:32:18 INFO - Successfully installed flake8 mccabe pyflakes pep8 12:32:18 INFO - Cleaning up... 12:32:18 INFO - Return code: 0 12:32:18 INFO - Running post-action listener: _resource_record_post_action 12:32:18 INFO - Running post-action listener: _start_resource_monitoring 12:32:18 INFO - Starting resource monitoring. 12:32:18 INFO - ##### 12:32:18 INFO - ##### Running install step. 12:32:18 INFO - ##### 12:32:18 INFO - Running pre-action listener: _resource_record_pre_action 12:32:18 INFO - Running main action method: install 12:32:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:32:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:32:18 INFO - Reading from file tmpfile_stdout 12:32:18 INFO - Detecting whether we're running mozinstall >=1.0... 12:32:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:32:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:32:18 INFO - Reading from file tmpfile_stdout 12:32:18 INFO - Output received: 12:32:18 INFO - Usage: mozinstall [options] installer 12:32:18 INFO - Options: 12:32:18 INFO - -h, --help show this help message and exit 12:32:18 INFO - -d DEST, --destination=DEST 12:32:18 INFO - Directory to install application into. [default: 12:32:18 INFO - "/builds/slave/test"] 12:32:18 INFO - --app=APP Application being installed. [default: firefox] 12:32:18 INFO - mkdir: /builds/slave/test/build/application 12:32:18 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:32:18 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:32:50 INFO - Reading from file tmpfile_stdout 12:32:50 INFO - Output received: 12:32:50 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 12:32:50 INFO - Running post-action listener: _resource_record_post_action 12:32:50 INFO - ##### 12:32:50 INFO - ##### Running run-tests step. 12:32:50 INFO - ##### 12:32:50 INFO - Running pre-action listener: _resource_record_pre_action 12:32:50 INFO - Running main action method: run_tests 12:32:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:32:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:32:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:32:50 INFO - 2016-05-05 12:32:50.318 screenresolution[1640:7490] starting screenresolution argv=screenresolution get 12:32:50 INFO - 2016-05-05 12:32:50.321 screenresolution[1640:7490] Display 0: 1600x1200x32@60 12:32:50 INFO - 2016-05-05 12:32:50.326 screenresolution[1641:7492] starting screenresolution argv=screenresolution list 12:32:50 INFO - Available Modes on Display 0 12:32:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:32:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:32:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:32:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:32:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:32:50 INFO - Intel Iris: 12:32:50 INFO - Chipset Model: Intel Iris 12:32:50 INFO - Type: GPU 12:32:50 INFO - Bus: Built-In 12:32:50 INFO - VRAM (Dynamic, Max): 1536 MB 12:32:50 INFO - Vendor: Intel (0x8086) 12:32:50 INFO - Device ID: 0x0a2e 12:32:50 INFO - Revision ID: 0x0009 12:32:50 INFO - Displays: 12:32:50 INFO - Display: 12:32:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:32:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:32:50 INFO - Display Serial Number: 1600x1200 60 12:32:50 INFO - Main Display: Yes 12:32:50 INFO - Mirror: Off 12:32:50 INFO - Online: Yes 12:32:50 INFO - Rotation: Supported 12:32:50 INFO - Return code: 0 12:32:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:32:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:32:50 INFO - Python 2.7.3 12:32:50 INFO - Return code: 0 12:32:50 INFO - grabbing minidump binary from tooltool 12:32:50 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:32:50 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 0x10931ee00>, '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 0x109187db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109319b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:32:50 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:32:50 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:32:50 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:32:50 INFO - Return code: 0 12:32:50 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:32:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:32:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:32:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:32:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:32:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 12:32:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 12:32:51 INFO - Return code: 0 12:32:51 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/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0171', '--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:32:51 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/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0171 --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:32:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AobOWwxtbW/Render', 12:32:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:32:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:32:51 INFO - 'HOME': '/Users/cltbld', 12:32:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:32:51 INFO - 'LOGNAME': 'cltbld', 12:32:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:32:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:32:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:32:51 INFO - 'MOZ_NO_REMOTE': '1', 12:32:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:32:51 INFO - 'NO_EM_RESTART': '1', 12:32:51 INFO - 'PAGER': '/bin/cat', 12:32:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:32:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:32:51 INFO - 'PWD': '/builds/slave/test', 12:32:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:32:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:32:51 INFO - 'SHELL': '/bin/bash', 12:32:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rPx3C0vPJE/Listeners', 12:32:51 INFO - 'TMPDIR': '/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/', 12:32:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:32:51 INFO - 'USER': 'cltbld', 12:32:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:32:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:32:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:32:51 INFO - 'XPC_FLAGS': '0x0', 12:32:51 INFO - 'XPC_SERVICE_NAME': '0', 12:32:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:32:51 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/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0171', '--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:32:51 INFO - mozversion application_buildid: 20160505103448 12:32:51 INFO - mozversion application_changeset: cb425f373a802dc57c8fefffad95487334467ec2 12:32:51 INFO - mozversion application_display_name: FirefoxDeveloperEdition 12:32:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:32:51 INFO - mozversion application_name: Firefox 12:32:51 INFO - mozversion application_remotingname: firefox-dev 12:32:51 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:32:51 INFO - mozversion application_vendor: Mozilla 12:32:51 INFO - mozversion application_version: 48.0a2 12:32:51 INFO - mozversion platform_buildid: 20160505103448 12:32:51 INFO - mozversion platform_changeset: cb425f373a802dc57c8fefffad95487334467ec2 12:32:51 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:32:51 INFO - mozversion platform_version: 48.0a2 12:32:51 INFO - using testdate: 1462476771 12:32:51 INFO - actual date: 1462476771 12:32:51 INFO - starting webserver on 'localhost:49275' 12:32:51 INFO - SUITE-START | Running 2 tests 12:32:51 INFO - TEST-START | dromaeo_css 12:32:51 INFO - operating with platform_type : mac_ 12:32:51 INFO - Initialising browser for dromaeo_css test... 12:32:51 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/tmptPNvR6/profile http://localhost:49275/getInfo.html) 12:32:54 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 12:32:54 INFO - PROCESS | 1659 | colorDepth:24 12:32:54 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 12:32:54 INFO - PROCESS | 1659 | __metrics 12:32:54 INFO - PROCESS | 1659 | 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:32:54 ERROR - PROCESS | 1659 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 12:32:55 INFO - TEST-INFO | 1659: exit 0 12:32:55 INFO - Browser initialized. 12:32:55 INFO - Running cycle 1/1 for dromaeo_css test... 12:32:55 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/tmptPNvR6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:33:07 INFO - PROCESS | 1661 | RSS: Main: 211640320 12:33:07 INFO - PROCESS | 1661 | 12:33:58 INFO - PROCESS | 1661 | 1462476838736 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:36:09 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 12:36:10 INFO - PROCESS | 1661 | RSS: Main: 346263552 12:36:10 INFO - PROCESS | 1661 | 12:39:13 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 12:39:14 INFO - PROCESS | 1661 | RSS: Main: 348991488 12:39:14 INFO - PROCESS | 1661 | 12:42:16 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 12:42:17 INFO - PROCESS | 1661 | RSS: Main: 359677952 12:42:17 INFO - PROCESS | 1661 | 12:45:19 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 12:45:20 INFO - PROCESS | 1661 | RSS: Main: 356159488 12:45:20 INFO - PROCESS | 1661 | 12:48:23 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 12:48:24 INFO - PROCESS | 1661 | RSS: Main: 324001792 12:48:24 INFO - PROCESS | 1661 | 12:51:26 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 12:51:27 INFO - PROCESS | 1661 | RSS: Main: 361955328 12:51:27 INFO - PROCESS | 1661 | 12:51:27 INFO - PROCESS | 1661 | __start_tp_report 12:51:27 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 12:51:27 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 12:51:27 INFO - PROCESS | 1661 | |i|pagename|runs| 12:51:27 INFO - PROCESS | 1661 | |0;dojo.html;3401.3197360527893;3478.982605086974;3481.756277060606;3510.9648903510956;3544.5214895989034;5828.562857785667;5838.678872662002;5861.589688721786;5864.941350586503;5877.471027607509;5815.447532484418;5860.472557469827;5873.706314684259;5878.676672783006;5878.823635290937;4302.333138363559;5197.506236907488;5244.1347177715825;5315.840524784263;5330;5318.361796584415;5318.6011049171275;5327.840164795043;5330.440303768088;5334.5999050071205;5282.788688452457;5316.73416329184;5323.8136665216725;5341.786328546873;5346.786128554872;5342.1452567589;5346.198070289449;5351.839444816662;5364.249005139283;5386.569074474032;5113.411957624847;5283.762230699628;5287;5287.286216360781;5291.312129423189;5615.578831587647;5635.633683810539;5648.43515648436;5680.3467601225975;5701.087825947829;6308.495320374405;6322.051692246154;6327.27236367819;6350.936490635081;6358.713857876407;6206.162168107293;6326.177596912372;6370.649614271213;6383.553151279412;6391.808245752588;4985.227289770091;5904.2029326040865;5955.083024728205;6018.8495287617725;6022.97609406402;6362.1093046973465;6362.427381535684;6364;6382.234131904108;6390.680465976682;6115.633062016204;6200.844978875564;6284.497240220863;6293.905591416133;6308.936910630835;6342.460890824239;6345.777897773556;6353.682315884187;6360.9045864312075;6362.395572420613;6293.465055470337;6299.464545513591;6317;6322.336154703782;6348.746050158034;5714.171445140431;6214.4407003369915;6309.936900631027;6319.178506794087;6391.616503009835;4681.204195286809;4937.703737775673;4972.0801651694555;4978.800847966113;4980.601551875841;4474.798634061475;4477.171723231148;4507.977460112744;4562.1560011398;4574.428196475441;5086.287919691246;5625.71871406428;5782.45066718661;5826.359100498942;5830.562707796932;6238.407351301619;6304.8423789404715;6349.523785715997;6349.682515874188;6377.266614339317;5741.19623252745;5804.2744656917885;5808.506276966421;5815.156802263648;5822.359540450547;5472.315960504937;5477.986572484103;5481.561475081984;5485.725713714378;5492.286002819608;6225.408586184305;6411.679416029273;6428.228612566429;6431.003194504953;6459.418652321314;6377.808665740035;6383.106365108795;6396.264429590657;6398.0083087121575;6398.488120950406;6422.229332480132;6423.325550817097;6432.421082102634;6432.646204458612;6449.226092868886;6135.35515879939;6268.749250029948;6312.242530896322;6345.460635846005;6359.586626869239;6166.105914642441;6318.9052164216655;6324.968375158095;6341.746330146742;6354.6822658867795;6043.758249669964;6048.969755151196;6216.191895053614;6220.720067596879;6231;6011.218541589566;6141.969290153521;6178.073289006506;6181.629102253879;6224.533160013108;5522.558195344443;6020.909686354796;6026.487748541423;6071.058985857199;6074.696265186812;6017.2779266488305;6202.906956395567;6217.564770466024;6229.657368844576;6252.062190671436;5946.851328716817;5982.820515384546;6124.74712746768;6131.185821632809;6154.353792851686 12:51:27 INFO - PROCESS | 1661 | |1;ext.html;508.14631419215715;513.9203423469363;530.2947080383091;550.9916852160546;571.9561799715519;30048.248793780167;30982.845085774596;31086;31092.844535777316;38654;43016.56983430173;43946.34080488795;44045.898852528706;44063.33904991428;44114.779426102905;9467.857982130256;9706.61173553057;9722.513874306256;9739.366941148837;9752.414855108716;8430.283425908805;8684.609192586317;9325.673601423918;9502.667406640769;9693.079157480028;9448.338616296862;9704.902950970505;11450.141239407036;11476.598319058834;11498.137639677016;11380.203385763;11513.30920144794;11531.596394126165;11539.538418463293;11564.537418503334;8387.790305242417;9381.953090234574;11162.218644694874;11342.376169310684;11343.716407089807;38475.42286865699;39080.80459597712;39085.218295634215;39098;39142.21715565673;3215.389076075538;3239.6598357172393;3325.800451972865;3362.3611513812543;3512.367773800715;27380.863095684592;27484.862575687195;27741.167764967082;27990.440191196063;28824.135275941546;2346.2775117712063;2700.4329090890938;2732.0164740693335;2741.136541989267;2819.6898341182455;472572;473617;474188;474767;475727;60785;62147.37852621416;62277.754444910846;62325.37674623286;62330;60716.69641651763;60719.267632956624;60728.69635651882;60785.392146078855;60799.69600152059;16521.84347095716;16780.664386712197;16814;16822.495325140266;16822.831771682126;11640.417979101012;12765.68085797844;12838.358082095858;12844.614661560168;12856.871431285568;16598.502044938672;18550.628987420176;18695.345662901735;18989.050547472572;19099.331523396613;8026.759197223976;8239.011318641682;8261.421700480909;8282.58587070644;8336.499810011419;942.4392486470539;957.7797106665366;994.6170724271208;1010.6260683547134;1041.1254546181244;28381.716182837907;28958.42083158325;29358;29719.851400742857;29802.403951921275;895.2199191350844;1041.5417216424748;1139.0545846947014;1139.0602752728867;1210.2556927489532;13010.479580816851;14483.565493035227;15076.849231507764;15144.54566363011;15458.458953936559;664740;666993;673205;674406;675048;57048.42951570431;57412.13881791777;57637.13544296839;57648.558786030684;57659.13511297334;1353.6683512539491;1492.5000124958044;1520.5590378790469;1524.9466268680765;1535.646801235722;27764;28168.154955350554;28423.573646395733;28718.71281287244;30099.09702708922;57013.71493142508;57888;57946.7102664484;58100.41899581119;58118;618319;627973.7202627915;628249;628523;628670;31768.523472148398;38215.61784382121;38918;39097;39677.60322396739;41727.58272417237;41751.1649767009;41831.37252941148;41867.58132418759;41950;28129.296767580974;32177;34633;35333.646663533036;35814.64185358113;34507;34769.956901293;35434.645653543135;35449;36636;35302;35528.822355888056;36660.81669591635;36914.07714807151;37050;30986.380272393973;31849;32134.67865321317;32194.19514512156;32242.35515289728;18098.004609746065;24178.63732044056;24386.878065610268;24608.75391246136;24694.382640434134 12:51:27 INFO - PROCESS | 1661 | |2;jquery.html;400.85368840373263;406.46752753892395;410.8438793258561;419.70102532661423;420.0611633000244;20376.898115509437;20573.794262057392;20810;21356.145754169815;21356.35930922073;19209.519762005955;19928.302509412173;20528.692069618966;20531.692024619642;21238.469038274052;1665.7418100194488;1738.5305967388797;6056.242969628785;6290.402411770898;6295.653739044351;6059.818205453844;6186.907196392058;6223.657698826562;6248.4064013918605;6259.561830671855;6025.939740602604;6180.042093475518;6203.968980155116;6212.192414986045;6222.315545290037;6051.092336149569;6106.72519736613;6167.9074813877605;6173.536984726119;6177.814665559996;5305.65513241638;5312.946870531284;5330.960462709773;5365.490278423544;5375.6774593524515;25930.870345648338;26607.866960665073;26657;26920.73079269202;27409;2164.545445456456;2295.908163673451;2376.9524609507885;2419.3104965085045;2681.785457163422;14586.051906626035;15368.924175307733;15427.460038898585;15508.5347439577;15512.92243538775;1704.9147542622943;1763.3651885321274;1781.32309722306;1834.523024013766;1844.8985305808176;113831;113952.99999999917;115115;115409;116184;11064;11341.319520828776;11785.646430606925;11829.52681892732;12007.339596322112;14615.488457903923;14663.046901951338;14679;14686.412543498354;14710.852891470948;9005.864912026325;9110.954445227864;9122.81754364909;9146.588403521857;9280.72157835266;9206.493642849708;9207.815843683224;9229.35394522377;9296.488693121808;9337.953310233406;6789.287124851919;6881.90102018879;6931.376176144171;6944.965275173693;7018.473614478935;1917.2331067573082;1924.2399252295545;1936.3706795291305;1960.9313674021341;1963.7416970484057;716.913970323554;730.6837325677615;882.6778225947482;978.7797745507204;1060.188955449082;21083;21791.455213619785;21882.562348753254;22019.66970495444;22047.66928496074;996.1681995533811;1011.2112552209142;1012.448215722433;1020.7244044108049;1046.0742243114746;9883.654072107442;11415.714607134722;11872;11924.46339914706;11925.582604608797;619155;628101;636661;645815;649848;128141;133349;138085;139203;144269;1036.7563622548696;1043.994780026095;1045.278757657214;1066.328213225663;1071.8231491803922;18911.054447277307;19181.520461988563;19627.214911403385;19667.21331146738;19780.208791648172;50978.19512340279;51213.74393128011;51579.22631160611;51774.741126294124;53135.93728125493;61733.69133154306;76965.61517192378;77414;77925.61037194778;82491.58754206191;6297.433231009231;8287.461315014505;8464.915350846413;8507.276881465144;8598.570071496526;11395.487203075716;12966.546170883974;13413.18323152149;13423.798643020558;13668.589942301747;9844.803103938024;11621.477033533341;11643.359615221245;11903.40482975865;12023.579174728842;11408.714782130513;11652.951234388946;11657.591984280509;11675.824862627238;11887.28676279426;11915.582954596548;11927.940360298144;11931.343776092399;12164;12229.449674764835;6822.863542729018;8213.178682131978;8335.416520843726;8364;8656.26421754158;11708.531658733555;11774.3524106175;11967.042636589225;12058.698532536406;12068.698282543006 12:51:27 INFO - PROCESS | 1661 | |3;mootools.html;1445.8337291211515;1592.0686398456903;1597.5127586086246;1818.15455813047;1943.9125239364228;7872.803179920497;8611.827763444735;8802.07578204289;8813.162749538784;8822.206001459866;8421.536815475147;8654.177853103945;8658.00432950211;8658.134186581354;8664.176903194202;2166.3825809644254;2408.4460574067975;2437.695288088989;2467.481828815951;2480.379905023744;2473.4434752180705;2499.8125140614434;2504.3864253257975;2507.0849140063838;2519.2945975126995;2404.3868813452546;2450.068973789966;2451.2278632230878;2454.8772561371948;2460.3111128883943;2144.742630884296;2381.3927448500694;2431.0275889644195;2457.1154384421593;2457.668214791016;2442.255112190781;2466.580681284186;2470.6170543565595;2482.1808803094937;2492.3644470660047;8632.007319158314;8687.826243475096;8691.652333906635;8711.259542938858;8719.564021798884;5149.407818100892;5360.437154098841;5383.650062745909;5387.488188622114;5398.946010539923;3436.6563343665684;3505.106197919514;3604.7476676632646;3615.3130905128205;3633.8364773585254;1021.0759262867117;1043.8956104389576;1045.63402809017;1048.8217003109414;1076.3272954403499;11519.48162332697;11616.477258523386;11630.592929247521;11632.360220187886;11634.883651163549;4544.636429085665;4576.4737055239075;4673.859784206479;4695.295705644113;4705.976470117627;9248;9261.351705380559;9273.768155796024;9277.628894844265;9288.303377246599;5286.207068939613;5289.735513224324;5322.201669749568;5323.22813192085;5326.7869285228935;4243.745375277494;4448.332750087448;4540.182767101954;4572.588467037916;4575.450945886449;3272.5582046423906;3828.8468461261236;3844.8269827857252;3909.179072394829;3940.7438516496336;1210.2980271442543;1219.2501611508858;1223.473906220334;1234.0806099455963;1234.7530493901256;831.2061980808313;854.9145085491401;897.9146981036791;909.6088681866861;913.5295322908707;5481.862953426196;5733.971330143406;5739.196512488255;5907.881842363128;5943.019401798655;944.617429940875;947.3368641950663;956.6651671914774;970.9465979371203;982.4891056650501;3756.718246131551;3784.4323351496946;3882.378819388941;3920.8039598019986;3991.002249437641;10312.278140530241;10602.992715691997;10705.411202383788;10712.46437678113;10897.67306980792;10615.628453004108;10723.94638026805;10747.408892510986;10753.83869241962;10786.94606526978;6690.899636505456;7943.880841787378;7991.44059915812;8199.508029518249;8310.376721745894;5142.357205349332;5673.149027645886;5765;5797.188393624811;5830.504407125272;9364.438133712;9813.509324533887;9826.115649591571;9931.155851752394;9981.700548983252;9933.503324833873;10190.337628054449;10193.082622564005;10227.795444090927;10310.639127630497;6535.3464653534265;6542.901856472252;7004.894926576208;7110.39561637246;7116.537425067561;8312.33501319905;8334.6666133354;8375.371847111614;8393.874091888505;8398.748037558884;7334.816629584303;7449.255074492507;7508.812279693051;7593.012908321883;7632.045994250719;7203.02759127532;7280.271972802678;7281.417486600953;7413.518121322097;7465.776026719207;7030.191527974348;7088.929110709033;7118.359347658736;7123.180834204133;7128.429725621834;4743.715377077387;4846.36997190363;4887.951120488892;4896.583790377716;4927.211646136601;4246.150769846105;4898.92651610219;5071.18860982241;5093.23601459784;5234.13636749932 12:51:27 INFO - PROCESS | 1661 | |4;prototype.html;320.04147578003875;321.62530651790667;328.2483113669696;329.4547523848032;333.36993083073014;5278.6568873023225;5452.773126046647;5476.17857321402;5488.255872063968;5515.227868098465;5365.678059316444;5401.216823560581;5404.864878378033;5407.324084489439;5428.511433970942;3958.940615890764;4005.7596544207295;4075.6535694466006;4184.761152783261;4254.97872510637;3643.908902277438;3743.9812800936093;3929.6659783918403;4233.348993892384;4234.586285817086;3551.827896794052;3986.222686576119;4128.731632443882;4149.336106223005;4167.791610419482;3270.9018729438153;3468.6357932416986;3987.4417581538464;4170.874873753793;4170.895727606804;3043.8478076096108;3173.9525956434322;3189.8405079746153;3629.618890016563;3650.653187947141;4566.817327306904;5194.961007798455;5202.745227320396;5258;5263.894722105575;4144.647704945083;4390.495093064306;4507.504174540798;4691.545620857511;4839.201531747258;5676.716164191774;5682.318121825408;5686.488216060533;5698.6580805151825;5703.629264097862;3914.432407300925;3914.8825535233987;3925.9018524536827;3934.449177115206;3940.7832569208967;5626.621477737939;5888.352281249059;6004.609700369506;6025.559648342429;6052.818415447544;3387.6612338765926;3570.928581428409;3915.882523524299;3961.9009524761536;4006.659433948089;3436.350551735181;4074.553533495566;4078.3882417636996;4081.4081958115908;4090.6727461802984;4039.3940908863906;4510.892865062514;4582.900103975022;4731.503192164842;4737.620990320766;5231.790728370823;5561.610687251854;5760.423957604206;5786.218860453785;5787.652740835563;1459.3870574358796;1474.8820094392422;1476.6751314710746;1483.9629009274854;1484.9851501485064;906.5875026862761;927.1979737527125;958.5063692198523;965.6234068713208;982.3271059324386;5726.856828579319;5733.88532229353;5740.856478588069;5749.470233097424;5773.769049237983;5327.188755823039;6382.968085159545;6389.137466442064;6390.488760899117;6458.870822583522;4357.281048626941;4753.667243292936;4937.876553086202;4973.129702302155;5015.3480047593575;2810.889698569054;3366.377220214268;3367.7979321240805;3389.67798059184;3398.456247000468;5445.747478080064;6129.751231847128;6349;6745.9662701686175;6751.392374686302;6049.939500604937;6060.393960603904;6521.673916304166;6618.536702430783;6626.171728533934;6582.407583317525;6643.7674681385915;6705.826480366014;6733.754255462755;6749.2238392584495;6353.301136875032;6455.580387274812;6459.967700161469;6460.67696615158;6500.512461565496;6279.3720627938665;6337.9366206337345;6338.366163383625;6357.523185761179;6384.425401713868;5875.29736321297;5998.34018258;6402.7194561087035;6715.56348837324;6716;4204.600562946515;4208.179405016115;4232.703710740218;4239.342901850218;4242.109157076925;5423.5932305076485;6189.752409903554;6454.837129806455;6495.350464953467;6514.804555863332;5349.732513374393;5681.863627274479;6068.544859135671;6264.279607844974;6285.779997700059;6233.532485063548;6235.781747638811;6238;6269.279032911274;6293.496520278458;5885;6077.4226448487325;6082.756689732538;6098.847528811815;6114.755409783559;5051.777218024057;5238.0833354162105;5943.465088142088;6220.595661282002;6276;5886.94113058864;5989.64062156272;6150;6166.753329866755;6194.225721784777 12:51:27 INFO - PROCESS | 1661 | |5;yui.html;663.3267233757736;730.4302643937731;736.6611358774963;745.8955746195534;754.4379437319199;12640.620781376561;14871.702565948675;15140.167290799016;16661.00033997961;17257.30970761168;15576.376944922189;15646;15743.763843542356;15754.212289385543;15774;721.8051126195924;745.4185735126609;749.2208103572278;925.4539821505293;932.5663566441604;756.5082696247456;759.6961215513784;761.2007392238128;898.4294972692355;942.0955882352949;982.6511588386127;989.0356902020544;990.1336330710628;991.3605724307818;992.1071036067561;979.2900147393154;982.670805280232;982.9262805289632;983.5033308179395;984.0700537991601;568.5622071005303;706.3642721550594;729.580491217552;730.9342159205697;735.2500449541491;15233.466828661054;15285.694286114216;15297.770533442008;15311.693766124616;15338.07971521701;10158.847617285746;12354.876451235463;12446;12587.433565489575;12839.229646221256;10060.798784024279;10114.494275286206;10170.338927969658;10361.585536578528;10432.478376081166;3439.914002149966;3459.705924996378;3590.6947909427467;3595.280943811222;3596.3886139356373;70865.22836929117;73649;73667.26332736657;73683.89474157892;73798.26201737967;9402.623895044258;9555.617775289049;9718.562664680107;9767.120959113714;9830.066143716336;20443.28448504325;20489.692654610193;20531.794682052987;20534.897325513575;20540.589188216152;7267.091613548307;7274.272572742683;7539.246075392527;7583.9620801895635;7702.614869256627;4035.3946907963455;4085.4893138357706;4100.34394496879;4135.29699951009;4295.871123866289;2962.4519463899246;3507.9123021924147;3508.4035713928693;3521.454174602941;3565.4117070683046;610.5512448350547;634.8508100596356;635.123529529256;635.3329004545209;650.2879347114902;536.3590509341377;578.0721941284262;584.0654952076643;599.5263741644052;608.5831205624162;7295.489315747953;7733.806654833674;8101.432899696964;8156.551389673625;8193.221643943816;513.6507175120954;575.8358867722762;596.3440215762713;597.1490625858135;610.1305639463747;3179.459491886385;3764.962350376516;3768.5854555998817;3815.809209539512;3986.940195897064;88463.55768221247;90606.54696726605;91420;91946.62080068805;97107.51446242724;72645.63677181686;79368.603156985;81690.59154704188;83048.58475707583;89791.55104224438;73703;73988;74121;74278;75024.24975750282;8156.388270879826;8160.061592916891;8216.972878390201;8383.395140089577;8415.5792210388;45415.77292113518;45629.7525784539;45688.771556142005;46807;47381.81545461391;59996;62398;63136.684316578125;65174.67412662906;65577.34422655712;10459.68620941373;10696.946515267375;11255.118673716905;11618.49194772711;11636.476358564054;10460.843087353544;11673.007794337576;11708.414579270831;12130.393480325773;12397.75204495923;7185.856282874209;11287.435628218393;11787.469563869818;11912.166148369532;11921.52313907434;10757;11183.72040698989;11658.76682466329;11900.642980710592;11968.16222864391;11288.492017859051;11340.319580825177;11561.42192890369;11613;11649.301041937511;9663.323567350499;9878.851817222603;9887.950560247153;9983.400995940267;10267.691969240936;8679.739607811776;9076.546172691471;9543.618255269988;9546.761330966503;9685.612575497184 12:51:27 INFO - PROCESS | 1661 | __end_tp_report 12:51:27 INFO - PROCESS | 1661 | __start_cc_report 12:51:27 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,1214 12:51:27 INFO - PROCESS | 1661 | __end_cc_report 12:51:27 INFO - PROCESS | 1661 | __startTimestamp1462477887225__endTimestamp 12:51:27 INFO - PROCESS | 1661 | 12:51:27 INFO - PROCESS | 1661 | ------- Summary: start ------- 12:51:27 INFO - PROCESS | 1661 | Number of tests: 6 12:51:27 INFO - PROCESS | 1661 | 12:51:27 INFO - PROCESS | 1661 | [#0] dojo.html Cycles:165 Average:5820.27 Median:6026.49 stddev:656.86 (10.9%) stddev-sans-first:630.87 12:51:27 INFO - PROCESS | 1661 | Values: 3401.3 3479.0 3481.8 3511.0 3544.5 5828.6 5838.7 5861.6 5864.9 5877.5 5815.4 5860.5 5873.7 5878.7 5878.8 4302.3 5197.5 5244.1 5315.8 5330.0 5318.4 5318.6 5327.8 5330.4 5334.6 5282.8 5316.7 5323.8 5341.8 5346.8 5342.1 5346.2 5351.8 5364.2 5386.6 5113.4 5283.8 5287.0 5287.3 5291.3 5615.6 5635.6 5648.4 5680.3 5701.1 6308.5 6322.1 6327.3 6350.9 6358.7 6206.2 6326.2 6370.6 6383.6 6391.8 4985.2 5904.2 5955.1 6018.8 6023.0 6362.1 6362.4 6364.0 6382.2 6390.7 6115.6 6200.8 6284.5 6293.9 6308.9 6342.5 6345.8 6353.7 6360.9 6362.4 6293.5 6299.5 6317.0 6322.3 6348.7 5714.2 6214.4 6309.9 6319.2 6391.6 4681.2 4937.7 4972.1 4978.8 4980.6 4474.8 4477.2 4508.0 4562.2 4574.4 5086.3 5625.7 5782.5 5826.4 5830.6 6238.4 6304.8 6349.5 6349.7 6377.3 5741.2 5804.3 5808.5 5815.2 5822.4 5472.3 5478.0 5481.6 5485.7 5492.3 6225.4 6411.7 6428.2 6431.0 6459.4 6377.8 6383.1 6396.3 6398.0 6398.5 6422.2 6423.3 6432.4 6432.6 6449.2 6135.4 6268.7 6312.2 6345.5 6359.6 6166.1 6318.9 6325.0 6341.7 6354.7 6043.8 6049.0 6216.2 6220.7 6231.0 6011.2 6142.0 6178.1 6181.6 6224.5 5522.6 6020.9 6026.5 6071.1 6074.7 6017.3 6202.9 6217.6 6229.7 6252.1 5946.9 5982.8 6124.7 6131.2 6154.4 12:51:27 INFO - PROCESS | 1661 | 12:51:27 INFO - PROCESS | 1661 | [#1] ext.html Cycles:180 Average:71714.06 Median:35488.91 stddev:159720.07 (450.1%) stddev-sans-first:160078.65 12:51:27 INFO - PROCESS | 1661 | Values: 508.1 513.9 530.3 551.0 572.0 30048.2 30982.8 31086.0 31092.8 38654.0 43016.6 43946.3 44045.9 44063.3 44114.8 9467.9 9706.6 9722.5 9739.4 9752.4 8430.3 8684.6 9325.7 9502.7 9693.1 9448.3 9704.9 11450.1 11476.6 11498.1 11380.2 11513.3 11531.6 11539.5 11564.5 8387.8 9382.0 11162.2 11342.4 11343.7 38475.4 39080.8 39085.2 39098.0 39142.2 3215.4 3239.7 3325.8 3362.4 3512.4 27380.9 27484.9 27741.2 27990.4 28824.1 2346.3 2700.4 2732.0 2741.1 2819.7 472572.0 473617.0 474188.0 474767.0 475727.0 60785.0 62147.4 62277.8 62325.4 62330.0 60716.7 60719.3 60728.7 60785.4 60799.7 16521.8 16780.7 16814.0 16822.5 16822.8 11640.4 12765.7 12838.4 12844.6 12856.9 16598.5 18550.6 18695.3 18989.1 19099.3 8026.8 8239.0 8261.4 8282.6 8336.5 942.4 957.8 994.6 1010.6 1041.1 28381.7 28958.4 29358.0 29719.9 29802.4 895.2 1041.5 1139.1 1139.1 1210.3 13010.5 14483.6 15076.8 15144.5 15458.5 664740.0 666993.0 673205.0 674406.0 675048.0 57048.4 57412.1 57637.1 57648.6 57659.1 1353.7 1492.5 1520.6 1524.9 1535.6 27764.0 28168.2 28423.6 28718.7 30099.1 57013.7 57888.0 57946.7 58100.4 58118.0 618319.0 627973.7 628249.0 628523.0 628670.0 31768.5 38215.6 38918.0 39097.0 39677.6 41727.6 41751.2 41831.4 41867.6 41950.0 28129.3 32177.0 34633.0 35333.6 35814.6 34507.0 34770.0 35434.6 35449.0 36636.0 35302.0 35528.8 36660.8 36914.1 37050.0 30986.4 31849.0 32134.7 32194.2 32242.4 18098.0 24178.6 24386.9 24608.8 24694.4 12:51:27 INFO - PROCESS | 1661 | 12:51:27 INFO - PROCESS | 1661 | [#2] jquery.html Cycles:180 Average:36545.23 Median:11760.45 stddev:105849.85 (900.0%) stddev-sans-first:106112.00 12:51:27 INFO - PROCESS | 1661 | Values: 400.9 406.5 410.8 419.7 420.1 20376.9 20573.8 20810.0 21356.1 21356.4 19209.5 19928.3 20528.7 20531.7 21238.5 1665.7 1738.5 6056.2 6290.4 6295.7 6059.8 6186.9 6223.7 6248.4 6259.6 6025.9 6180.0 6204.0 6212.2 6222.3 6051.1 6106.7 6167.9 6173.5 6177.8 5305.7 5312.9 5331.0 5365.5 5375.7 25930.9 26607.9 26657.0 26920.7 27409.0 2164.5 2295.9 2377.0 2419.3 2681.8 14586.1 15368.9 15427.5 15508.5 15512.9 1704.9 1763.4 1781.3 1834.5 1844.9 113831.0 113953.0 115115.0 115409.0 116184.0 11064.0 11341.3 11785.6 11829.5 12007.3 14615.5 14663.0 14679.0 14686.4 14710.9 9005.9 9111.0 9122.8 9146.6 9280.7 9206.5 9207.8 9229.4 9296.5 9338.0 6789.3 6881.9 6931.4 6945.0 7018.5 1917.2 1924.2 1936.4 1960.9 1963.7 716.9 730.7 882.7 978.8 1060.2 21083.0 21791.5 21882.6 22019.7 22047.7 996.2 1011.2 1012.4 1020.7 1046.1 9883.7 11415.7 11872.0 11924.5 11925.6 619155.0 628101.0 636661.0 645815.0 649848.0 128141.0 133349.0 138085.0 139203.0 144269.0 1036.8 1044.0 1045.3 1066.3 1071.8 18911.1 19181.5 19627.2 19667.2 19780.2 50978.2 51213.7 51579.2 51774.7 53135.9 61733.7 76965.6 77414.0 77925.6 82491.6 6297.4 8287.5 8464.9 8507.3 8598.6 11395.5 12966.5 13413.2 13423.8 13668.6 9844.8 11621.5 11643.4 11903.4 12023.6 11408.7 11653.0 11657.6 11675.8 11887.3 11915.6 11927.9 11931.3 12164.0 12229.4 6822.9 8213.2 8335.4 8364.0 8656.3 11708.5 11774.4 11967.0 12058.7 12068.7 12:51:27 INFO - PROCESS | 1661 | 12:51:27 INFO - PROCESS | 1661 | [#3] mootools.html Cycles:180 Average:5643.41 Median:4999.20 stddev:3145.76 (62.9%) stddev-sans-first:3138.77 12:51:27 INFO - PROCESS | 1661 | Values: 1445.8 1592.1 1597.5 1818.2 1943.9 7872.8 8611.8 8802.1 8813.2 8822.2 8421.5 8654.2 8658.0 8658.1 8664.2 2166.4 2408.4 2437.7 2467.5 2480.4 2473.4 2499.8 2504.4 2507.1 2519.3 2404.4 2450.1 2451.2 2454.9 2460.3 2144.7 2381.4 2431.0 2457.1 2457.7 2442.3 2466.6 2470.6 2482.2 2492.4 8632.0 8687.8 8691.7 8711.3 8719.6 5149.4 5360.4 5383.7 5387.5 5398.9 3436.7 3505.1 3604.7 3615.3 3633.8 1021.1 1043.9 1045.6 1048.8 1076.3 11519.5 11616.5 11630.6 11632.4 11634.9 4544.6 4576.5 4673.9 4695.3 4706.0 9248.0 9261.4 9273.8 9277.6 9288.3 5286.2 5289.7 5322.2 5323.2 5326.8 4243.7 4448.3 4540.2 4572.6 4575.5 3272.6 3828.8 3844.8 3909.2 3940.7 1210.3 1219.3 1223.5 1234.1 1234.8 831.2 854.9 897.9 909.6 913.5 5481.9 5734.0 5739.2 5907.9 5943.0 944.6 947.3 956.7 970.9 982.5 3756.7 3784.4 3882.4 3920.8 3991.0 10312.3 10603.0 10705.4 10712.5 10897.7 10615.6 10723.9 10747.4 10753.8 10786.9 6690.9 7943.9 7991.4 8199.5 8310.4 5142.4 5673.1 5765.0 5797.2 5830.5 9364.4 9813.5 9826.1 9931.2 9981.7 9933.5 10190.3 10193.1 10227.8 10310.6 6535.3 6542.9 7004.9 7110.4 7116.5 8312.3 8334.7 8375.4 8393.9 8398.7 7334.8 7449.3 7508.8 7593.0 7632.0 7203.0 7280.3 7281.4 7413.5 7465.8 7030.2 7088.9 7118.4 7123.2 7128.4 4743.7 4846.4 4888.0 4896.6 4927.2 4246.2 4898.9 5071.2 5093.2 5234.1 12:51:27 INFO - PROCESS | 1661 | 12:51:27 INFO - PROCESS | 1661 | [#4] prototype.html Cycles:180 Average:4855.24 Median:5426.05 stddev:1583.50 (29.2%) stddev-sans-first:1550.93 12:51:27 INFO - PROCESS | 1661 | Values: 320.0 321.6 328.2 329.5 333.4 5278.7 5452.8 5476.2 5488.3 5515.2 5365.7 5401.2 5404.9 5407.3 5428.5 3958.9 4005.8 4075.7 4184.8 4255.0 3643.9 3744.0 3929.7 4233.3 4234.6 3551.8 3986.2 4128.7 4149.3 4167.8 3270.9 3468.6 3987.4 4170.9 4170.9 3043.8 3174.0 3189.8 3629.6 3650.7 4566.8 5195.0 5202.7 5258.0 5263.9 4144.6 4390.5 4507.5 4691.5 4839.2 5676.7 5682.3 5686.5 5698.7 5703.6 3914.4 3914.9 3925.9 3934.4 3940.8 5626.6 5888.4 6004.6 6025.6 6052.8 3387.7 3570.9 3915.9 3961.9 4006.7 3436.4 4074.6 4078.4 4081.4 4090.7 4039.4 4510.9 4582.9 4731.5 4737.6 5231.8 5561.6 5760.4 5786.2 5787.7 1459.4 1474.9 1476.7 1484.0 1485.0 906.6 927.2 958.5 965.6 982.3 5726.9 5733.9 5740.9 5749.5 5773.8 5327.2 6383.0 6389.1 6390.5 6458.9 4357.3 4753.7 4937.9 4973.1 5015.3 2810.9 3366.4 3367.8 3389.7 3398.5 5445.7 6129.8 6349.0 6746.0 6751.4 6049.9 6060.4 6521.7 6618.5 6626.2 6582.4 6643.8 6705.8 6733.8 6749.2 6353.3 6455.6 6460.0 6460.7 6500.5 6279.4 6337.9 6338.4 6357.5 6384.4 5875.3 5998.3 6402.7 6715.6 6716.0 4204.6 4208.2 4232.7 4239.3 4242.1 5423.6 6189.8 6454.8 6495.4 6514.8 5349.7 5681.9 6068.5 6264.3 6285.8 6233.5 6235.8 6238.0 6269.3 6293.5 5885.0 6077.4 6082.8 6098.8 6114.8 5051.8 5238.1 5943.5 6220.6 6276.0 5886.9 5989.6 6150.0 6166.8 6194.2 12:51:27 INFO - PROCESS | 1661 | 12:51:27 INFO - PROCESS | 1661 | [#5] yui.html Cycles:180 Average:18052.36 Median:609.36 stddev:25305.10 (4152.8%) stddev-sans-first:25342.40 12:51:27 INFO - PROCESS | 1661 | Values: 663.3 730.4 736.7 745.9 754.4 12640.6 14871.7 15140.2 16661.0 17257.3 15576.4 15646.0 15743.8 15754.2 15774.0 721.8 745.4 749.2 925.5 932.6 756.5 759.7 761.2 898.4 942.1 982.7 989.0 990.1 991.4 992.1 979.3 982.7 982.9 983.5 984.1 568.6 706.4 729.6 730.9 735.3 15233.5 15285.7 15297.8 15311.7 15338.1 10158.8 12354.9 12446.0 12587.4 12839.2 10060.8 10114.5 10170.3 10361.6 10432.5 3439.9 3459.7 3590.7 3595.3 3596.4 70865.2 73649.0 73667.3 73683.9 73798.3 9402.6 9555.6 9718.6 9767.1 9830.1 20443.3 20489.7 20531.8 20534.9 20540.6 7267.1 7274.3 7539.2 7584.0 7702.6 4035.4 4085.5 4100.3 4135.3 4295.9 2962.5 3507.9 3508.4 3521.5 3565.4 610.6 634.9 635.1 635.3 650.3 536.4 578.1 584.1 599.5 608.6 7295.5 7733.8 8101.4 8156.6 8193.2 513.7 575.8 596.3 597.1 610.1 3179.5 3765.0 3768.6 3815.8 3986.9 88463.6 90606.5 91420.0 91946.6 97107.5 72645.6 79368.6 81690.6 83048.6 89791.6 73703.0 73988.0 74121.0 74278.0 75024.2 8156.4 8160.1 8217.0 8383.4 8415.6 45415.8 45629.8 45688.8 46807.0 47381.8 59996.0 62398.0 63136.7 65174.7 65577.3 10459.7 10696.9 11255.1 11618.5 11636.5 10460.8 11673.0 11708.4 12130.4 12397.8 7185.9 11287.4 11787.5 11912.2 11921.5 10757.0 11183.7 11658.8 11900.6 11968.2 11288.5 11340.3 11561.4 11613.0 11649.3 9663.3 9878.9 9888.0 9983.4 10267.7 8679.7 9076.5 9543.6 9546.8 9685.6 12:51:27 INFO - PROCESS | 1661 | -------- Summary: end -------- 12:51:27 INFO - PROCESS | 1661 | 12:51:27 INFO - TEST-INFO | 1661: exit 0 12:51:27 INFO - TEST-OK | dromaeo_css | took 1115869ms 12:51:27 INFO - TEST-START | kraken 12:51:27 INFO - operating with platform_type : mac_ 12:51:27 INFO - Initialising browser for kraken test... 12:51:27 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/tmp8k1lbb/profile http://localhost:49275/getInfo.html) 12:51:30 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 12:51:30 INFO - PROCESS | 1670 | colorDepth:24 12:51:30 INFO - PROCESS | 1670 | Browser inner width/height: 1024/689 12:51:30 INFO - PROCESS | 1670 | __metrics 12:51:30 INFO - PROCESS | 1670 | 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:51:30 ERROR - PROCESS | 1670 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 12:51:30 INFO - TEST-INFO | 1670: exit 0 12:51:30 INFO - Browser initialized. 12:51:30 INFO - Running cycle 1/1 for kraken test... 12:51:30 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/T/tmp8k1lbb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:51:42 INFO - PROCESS | 1672 | RSS: Main: 211922944 12:51:42 INFO - PROCESS | 1672 | 12:51:45 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 12:51:46 INFO - PROCESS | 1672 | RSS: Main: 269746176 12:51:46 INFO - PROCESS | 1672 | 12:51:49 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 12:51:49 INFO - PROCESS | 1672 | RSS: Main: 284114944 12:51:49 INFO - PROCESS | 1672 | 12:51:53 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 12:51:53 INFO - PROCESS | 1672 | RSS: Main: 373579776 12:51:53 INFO - PROCESS | 1672 | 12:51:55 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 12:51:56 INFO - PROCESS | 1672 | RSS: Main: 277864448 12:51:56 INFO - PROCESS | 1672 | 12:51:58 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:51:59 INFO - PROCESS | 1672 | RSS: Main: 279748608 12:51:59 INFO - PROCESS | 1672 | 12:52:01 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 12:52:02 INFO - PROCESS | 1672 | RSS: Main: 317546496 12:52:02 INFO - PROCESS | 1672 | 12:52:05 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 12:52:05 INFO - PROCESS | 1672 | RSS: Main: 319160320 12:52:05 INFO - PROCESS | 1672 | 12:52:08 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 12:52:09 INFO - PROCESS | 1672 | RSS: Main: 316776448 12:52:09 INFO - PROCESS | 1672 | 12:52:11 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:52:11 INFO - PROCESS | 1672 | RSS: Main: 307523584 12:52:11 INFO - PROCESS | 1672 | 12:52:13 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:52:14 INFO - PROCESS | 1672 | RSS: Main: 314478592 12:52:14 INFO - PROCESS | 1672 | 12:52:16 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:52:17 INFO - PROCESS | 1672 | RSS: Main: 317108224 12:52:17 INFO - PROCESS | 1672 | 12:52:19 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:52:20 INFO - PROCESS | 1672 | RSS: Main: 322433024 12:52:20 INFO - PROCESS | 1672 | 12:52:23 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:52:23 INFO - PROCESS | 1672 | RSS: Main: 320380928 12:52:23 INFO - PROCESS | 1672 | 12:52:25 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 12:52:25 INFO - PROCESS | 1672 | RSS: Main: 384458752 12:52:25 INFO - PROCESS | 1672 | 12:52:25 INFO - PROCESS | 1672 | __start_tp_report 12:52:25 INFO - PROCESS | 1672 | _x_x_mozilla_page_load 12:52:25 INFO - PROCESS | 1672 | _x_x_mozilla_page_load_details 12:52:25 INFO - PROCESS | 1672 | |i|pagename|runs| 12:52:25 INFO - PROCESS | 1672 | |0;ai-astar;113;114;109;113;114;115;114;115;104;115 12:52:25 INFO - PROCESS | 1672 | |1;audio-beat-detection;169;147;143;206;153;174;165;117;168;118 12:52:25 INFO - PROCESS | 1672 | |2;audio-dft;184;187;187;185;185;184;184;191;186;181 12:52:25 INFO - PROCESS | 1672 | |3;audio-fft;71;77;76;151;56;54;59;66;133;57 12:52:25 INFO - PROCESS | 1672 | |4;audio-oscillator;89;87;141;78;84;92;123;79;80;132 12:52:25 INFO - PROCESS | 1672 | |5;imaging-gaussian-blur;89;85;88;86;88;87;136;126;88;101 12:52:25 INFO - PROCESS | 1672 | |6;imaging-darkroom;93;93;93;93;93;94;93;94;92;93 12:52:25 INFO - PROCESS | 1672 | |7;imaging-desaturate;84;90;84;71;127;134;108;86;84;85 12:52:25 INFO - PROCESS | 1672 | |8;json-parse-financial;60;64;66;67;65;65;63;82;60;58 12:52:25 INFO - PROCESS | 1672 | |9;json-stringify-tinderbox;61;65;64;77;77;71;69;69;67;67 12:52:25 INFO - PROCESS | 1672 | |10;stanford-crypto-aes;82;81;80;80;80;81;80;78;81;85 12:52:25 INFO - PROCESS | 1672 | |11;stanford-crypto-ccm;136;136;134;137;133;133;133;135;181;136 12:52:25 INFO - PROCESS | 1672 | |12;stanford-crypto-pbkdf2;171;168;163;168;175;168;172;166;169;167 12:52:25 INFO - PROCESS | 1672 | |13;stanford-crypto-sha256-iterative;68;67;68;69;68;69;68;68;68;67 12:52:25 INFO - PROCESS | 1672 | __end_tp_report 12:52:25 INFO - PROCESS | 1672 | __start_cc_report 12:52:25 INFO - PROCESS | 1672 | _x_x_mozilla_cycle_collect,2006 12:52:25 INFO - PROCESS | 1672 | __end_cc_report 12:52:25 INFO - PROCESS | 1672 | __startTimestamp1462477945773__endTimestamp 12:52:25 INFO - PROCESS | 1672 | 12:52:25 INFO - PROCESS | 1672 | ------- Summary: start ------- 12:52:25 INFO - PROCESS | 1672 | Number of tests: 14 12:52:25 INFO - PROCESS | 1672 | 12:52:25 INFO - PROCESS | 1672 | [#0] ai-astar Cycles:10 Average:112.60 Median:114.00 stddev:3.50 (3.1%) stddev-sans-first:3.71 12:52:25 INFO - PROCESS | 1672 | Values: 113.0 114.0 109.0 113.0 114.0 115.0 114.0 115.0 104.0 115.0 12:52:25 INFO - PROCESS | 1672 | 12:52:25 INFO - PROCESS | 1672 | [#1] audio-beat-detection Cycles:10 Average:156.00 Median:166.50 stddev:26.75 (16.1%) stddev-sans-first:27.96 12:52:25 INFO - PROCESS | 1672 | Values: 169.0 147.0 143.0 206.0 153.0 174.0 165.0 117.0 168.0 118.0 12:52:25 INFO - PROCESS | 1672 | 12:52:25 INFO - PROCESS | 1672 | [#2] audio-dft Cycles:10 Average:185.40 Median:185.50 stddev:2.63 (1.4%) stddev-sans-first:2.74 12:52:25 INFO - PROCESS | 1672 | Values: 184.0 187.0 187.0 185.0 185.0 184.0 184.0 191.0 186.0 181.0 12:52:25 INFO - PROCESS | 1672 | 12:52:25 INFO - PROCESS | 1672 | [#3] audio-fft Cycles:10 Average:80.00 Median:62.50 stddev:33.95 (54.3%) stddev-sans-first:35.85 12:52:25 INFO - PROCESS | 1672 | Values: 71.0 77.0 76.0 151.0 56.0 54.0 59.0 66.0 133.0 57.0 12:52:25 INFO - PROCESS | 1672 | 12:52:25 INFO - PROCESS | 1672 | [#4] audio-oscillator Cycles:10 Average:98.50 Median:82.00 stddev:23.91 (29.2%) stddev-sans-first:25.12 12:52:25 INFO - PROCESS | 1672 | Values: 89.0 87.0 141.0 78.0 84.0 92.0 123.0 79.0 80.0 132.0 12:52:25 INFO - PROCESS | 1672 | 12:52:25 INFO - PROCESS | 1672 | [#5] imaging-gaussian-blur Cycles:10 Average:97.40 Median:87.50 stddev:18.40 (21.0%) stddev-sans-first:19.27 12:52:25 INFO - PROCESS | 1672 | Values: 89.0 85.0 88.0 86.0 88.0 87.0 136.0 126.0 88.0 101.0 12:52:25 INFO - PROCESS | 1672 | 12:52:25 INFO - PROCESS | 1672 | [#6] imaging-darkroom Cycles:10 Average:93.10 Median:93.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 12:52:25 INFO - PROCESS | 1672 | Values: 93.0 93.0 93.0 93.0 93.0 94.0 93.0 94.0 92.0 93.0 12:52:25 INFO - PROCESS | 1672 | 12:52:25 INFO - PROCESS | 1672 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:84.00 stddev:20.70 (24.6%) stddev-sans-first:21.55 12:52:25 INFO - PROCESS | 1672 | Values: 84.0 90.0 84.0 71.0 127.0 134.0 108.0 86.0 84.0 85.0 12:52:25 INFO - PROCESS | 1672 | 12:52:25 INFO - PROCESS | 1672 | [#8] json-parse-financial Cycles:10 Average:65.00 Median:65.00 stddev:6.65 (10.2%) stddev-sans-first:6.80 12:52:25 INFO - PROCESS | 1672 | Values: 60.0 64.0 66.0 67.0 65.0 65.0 63.0 82.0 60.0 58.0 12:52:25 INFO - PROCESS | 1672 | 12:52:25 INFO - PROCESS | 1672 | [#9] json-stringify-tinderbox Cycles:10 Average:68.70 Median:69.00 stddev:5.21 (7.5%) stddev-sans-first:4.72 12:52:25 INFO - PROCESS | 1672 | Values: 61.0 65.0 64.0 77.0 77.0 71.0 69.0 69.0 67.0 67.0 12:52:25 INFO - PROCESS | 1672 | 12:52:25 INFO - PROCESS | 1672 | [#10] stanford-crypto-aes Cycles:10 Average:80.80 Median:81.00 stddev:1.81 (2.2%) stddev-sans-first:1.87 12:52:25 INFO - PROCESS | 1672 | Values: 82.0 81.0 80.0 80.0 80.0 81.0 80.0 78.0 81.0 85.0 12:52:25 INFO - PROCESS | 1672 | 12:52:25 INFO - PROCESS | 1672 | [#11] stanford-crypto-ccm Cycles:10 Average:139.40 Median:136.00 stddev:14.69 (10.8%) stddev-sans-first:15.53 12:52:25 INFO - PROCESS | 1672 | Values: 136.0 136.0 134.0 137.0 133.0 133.0 133.0 135.0 181.0 136.0 12:52:25 INFO - PROCESS | 1672 | 12:52:25 INFO - PROCESS | 1672 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.70 Median:168.50 stddev:3.33 (2.0%) stddev-sans-first:3.43 12:52:25 INFO - PROCESS | 1672 | Values: 171.0 168.0 163.0 168.0 175.0 168.0 172.0 166.0 169.0 167.0 12:52:25 INFO - PROCESS | 1672 | 12:52:25 INFO - PROCESS | 1672 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.00 Median:68.00 stddev:0.67 (1.0%) stddev-sans-first:0.71 12:52:25 INFO - PROCESS | 1672 | Values: 68.0 67.0 68.0 69.0 68.0 69.0 68.0 68.0 68.0 67.0 12:52:25 INFO - PROCESS | 1672 | -------- Summary: end -------- 12:52:25 INFO - PROCESS | 1672 | 12:52:26 INFO - TEST-INFO | 1672: exit 0 12:52:26 INFO - TEST-OK | kraken | took 58723ms 12:52:26 INFO - SUITE-END | took 1174s 12:52:26 INFO - Completed test suite (00:19:35) 12:52:26 INFO - javascript benchmark 12:52:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3401.3197360527893, 3478.982605086974, 3481.756277060606, 3510.9648903510956, 3544.5214895989034, 5828.562857785667, 5838.678872662002, 5861.589688721786, 5864.941350586503, 5877.471027607509, 5815.447532484418, 5860.472557469827, 5873.706314684259, 5878.676672783006, 5878.823635290937, 4302.333138363559, 5197.506236907488, 5244.1347177715825, 5315.840524784263, 5330.0, 5318.361796584415, 5318.6011049171275, 5327.840164795043, 5330.440303768088, 5334.5999050071205, 5282.788688452457, 5316.73416329184, 5323.8136665216725, 5341.786328546873, 5346.786128554872, 5342.1452567589, 5346.198070289449, 5351.839444816662, 5364.249005139283, 5386.569074474032, 5113.411957624847, 5283.762230699628, 5287.0, 5287.286216360781, 5291.312129423189, 5615.578831587647, 5635.633683810539, 5648.43515648436, 5680.3467601225975, 5701.087825947829, 6308.495320374405, 6322.051692246154, 6327.27236367819, 6350.936490635081, 6358.713857876407, 6206.162168107293, 6326.177596912372, 6370.649614271213, 6383.553151279412, 6391.808245752588, 4985.227289770091, 5904.2029326040865, 5955.083024728205, 6018.8495287617725, 6022.97609406402, 6362.1093046973465, 6362.427381535684, 6364.0, 6382.234131904108, 6390.680465976682, 6115.633062016204, 6200.844978875564, 6284.497240220863, 6293.905591416133, 6308.936910630835, 6342.460890824239, 6345.777897773556, 6353.682315884187, 6360.9045864312075, 6362.395572420613, 6293.465055470337, 6299.464545513591, 6317.0, 6322.336154703782, 6348.746050158034, 5714.171445140431, 6214.4407003369915, 6309.936900631027, 6319.178506794087, 6391.616503009835, 4681.204195286809, 4937.703737775673, 4972.0801651694555, 4978.800847966113, 4980.601551875841, 4474.798634061475, 4477.171723231148, 4507.977460112744, 4562.1560011398, 4574.428196475441, 5086.287919691246, 5625.71871406428, 5782.45066718661, 5826.359100498942, 5830.562707796932, 6238.407351301619, 6304.8423789404715, 6349.523785715997, 6349.682515874188, 6377.266614339317, 5741.19623252745, 5804.2744656917885, 5808.506276966421, 5815.156802263648, 5822.359540450547, 5472.315960504937, 5477.986572484103, 5481.561475081984, 5485.725713714378, 5492.286002819608, 6225.408586184305, 6411.679416029273, 6428.228612566429, 6431.003194504953, 6459.418652321314, 6377.808665740035, 6383.106365108795, 6396.264429590657, 6398.0083087121575, 6398.488120950406, 6422.229332480132, 6423.325550817097, 6432.421082102634, 6432.646204458612, 6449.226092868886, 6135.35515879939, 6268.749250029948, 6312.242530896322, 6345.460635846005, 6359.586626869239, 6166.105914642441, 6318.9052164216655, 6324.968375158095, 6341.746330146742, 6354.6822658867795, 6043.758249669964, 6048.969755151196, 6216.191895053614, 6220.720067596879, 6231.0, 6011.218541589566, 6141.969290153521, 6178.073289006506, 6181.629102253879, 6224.533160013108, 5522.558195344443, 6020.909686354796, 6026.487748541423, 6071.058985857199, 6074.696265186812, 6017.2779266488305, 6202.906956395567, 6217.564770466024, 6229.657368844576, 6252.062190671436, 5946.851328716817, 5982.820515384546, 6124.74712746768, 6131.185821632809, 6154.353792851686], "value": 5778.76374618855, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [508.14631419215715, 513.9203423469363, 530.2947080383091, 550.9916852160546, 571.9561799715519, 30048.248793780167, 30982.845085774596, 31086.0, 31092.844535777316, 38654.0, 43016.56983430173, 43946.34080488795, 44045.898852528706, 44063.33904991428, 44114.779426102905, 9467.857982130256, 9706.61173553057, 9722.513874306256, 9739.366941148837, 9752.414855108716, 8430.283425908805, 8684.609192586317, 9325.673601423918, 9502.667406640769, 9693.079157480028, 9448.338616296862, 9704.902950970505, 11450.141239407036, 11476.598319058834, 11498.137639677016, 11380.203385763, 11513.30920144794, 11531.596394126165, 11539.538418463293, 11564.537418503334, 8387.790305242417, 9381.953090234574, 11162.218644694874, 11342.376169310684, 11343.716407089807, 38475.42286865699, 39080.80459597712, 39085.218295634215, 39098.0, 39142.21715565673, 3215.389076075538, 3239.6598357172393, 3325.800451972865, 3362.3611513812543, 3512.367773800715, 27380.863095684592, 27484.862575687195, 27741.167764967082, 27990.440191196063, 28824.135275941546, 2346.2775117712063, 2700.4329090890938, 2732.0164740693335, 2741.136541989267, 2819.6898341182455, 472572.0, 473617.0, 474188.0, 474767.0, 475727.0, 60785.0, 62147.37852621416, 62277.754444910846, 62325.37674623286, 62330.0, 60716.69641651763, 60719.267632956624, 60728.69635651882, 60785.392146078855, 60799.69600152059, 16521.84347095716, 16780.664386712197, 16814.0, 16822.495325140266, 16822.831771682126, 11640.417979101012, 12765.68085797844, 12838.358082095858, 12844.614661560168, 12856.871431285568, 16598.502044938672, 18550.628987420176, 18695.345662901735, 18989.050547472572, 19099.331523396613, 8026.759197223976, 8239.011318641682, 8261.421700480909, 8282.58587070644, 8336.499810011419, 942.4392486470539, 957.7797106665366, 994.6170724271208, 1010.6260683547134, 1041.1254546181244, 28381.716182837907, 28958.42083158325, 29358.0, 29719.851400742857, 29802.403951921275, 895.2199191350844, 1041.5417216424748, 1139.0545846947014, 1139.0602752728867, 1210.2556927489532, 13010.479580816851, 14483.565493035227, 15076.849231507764, 15144.54566363011, 15458.458953936559, 664740.0, 666993.0, 673205.0, 674406.0, 675048.0, 57048.42951570431, 57412.13881791777, 57637.13544296839, 57648.558786030684, 57659.13511297334, 1353.6683512539491, 1492.5000124958044, 1520.5590378790469, 1524.9466268680765, 1535.646801235722, 27764.0, 28168.154955350554, 28423.573646395733, 28718.71281287244, 30099.09702708922, 57013.71493142508, 57888.0, 57946.7102664484, 58100.41899581119, 58118.0, 618319.0, 627973.7202627915, 628249.0, 628523.0, 628670.0, 31768.523472148398, 38215.61784382121, 38918.0, 39097.0, 39677.60322396739, 41727.58272417237, 41751.1649767009, 41831.37252941148, 41867.58132418759, 41950.0, 28129.296767580974, 32177.0, 34633.0, 35333.646663533036, 35814.64185358113, 34507.0, 34769.956901293, 35434.645653543135, 35449.0, 36636.0, 35302.0, 35528.822355888056, 36660.81669591635, 36914.07714807151, 37050.0, 30986.380272393973, 31849.0, 32134.67865321317, 32194.19514512156, 32242.35515289728, 18098.004609746065, 24178.63732044056, 24386.878065610268, 24608.75391246136, 24694.382640434134], "value": 19958.123604086177, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [400.85368840373263, 406.46752753892395, 410.8438793258561, 419.70102532661423, 420.0611633000244, 20376.898115509437, 20573.794262057392, 20810.0, 21356.145754169815, 21356.35930922073, 19209.519762005955, 19928.302509412173, 20528.692069618966, 20531.692024619642, 21238.469038274052, 1665.7418100194488, 1738.5305967388797, 6056.242969628785, 6290.402411770898, 6295.653739044351, 6059.818205453844, 6186.907196392058, 6223.657698826562, 6248.4064013918605, 6259.561830671855, 6025.939740602604, 6180.042093475518, 6203.968980155116, 6212.192414986045, 6222.315545290037, 6051.092336149569, 6106.72519736613, 6167.9074813877605, 6173.536984726119, 6177.814665559996, 5305.65513241638, 5312.946870531284, 5330.960462709773, 5365.490278423544, 5375.6774593524515, 25930.870345648338, 26607.866960665073, 26657.0, 26920.73079269202, 27409.0, 2164.545445456456, 2295.908163673451, 2376.9524609507885, 2419.3104965085045, 2681.785457163422, 14586.051906626035, 15368.924175307733, 15427.460038898585, 15508.5347439577, 15512.92243538775, 1704.9147542622943, 1763.3651885321274, 1781.32309722306, 1834.523024013766, 1844.8985305808176, 113831.0, 113952.99999999917, 115115.0, 115409.0, 116184.0, 11064.0, 11341.319520828776, 11785.646430606925, 11829.52681892732, 12007.339596322112, 14615.488457903923, 14663.046901951338, 14679.0, 14686.412543498354, 14710.852891470948, 9005.864912026325, 9110.954445227864, 9122.81754364909, 9146.588403521857, 9280.72157835266, 9206.493642849708, 9207.815843683224, 9229.35394522377, 9296.488693121808, 9337.953310233406, 6789.287124851919, 6881.90102018879, 6931.376176144171, 6944.965275173693, 7018.473614478935, 1917.2331067573082, 1924.2399252295545, 1936.3706795291305, 1960.9313674021341, 1963.7416970484057, 716.913970323554, 730.6837325677615, 882.6778225947482, 978.7797745507204, 1060.188955449082, 21083.0, 21791.455213619785, 21882.562348753254, 22019.66970495444, 22047.66928496074, 996.1681995533811, 1011.2112552209142, 1012.448215722433, 1020.7244044108049, 1046.0742243114746, 9883.654072107442, 11415.714607134722, 11872.0, 11924.46339914706, 11925.582604608797, 619155.0, 628101.0, 636661.0, 645815.0, 649848.0, 128141.0, 133349.0, 138085.0, 139203.0, 144269.0, 1036.7563622548696, 1043.994780026095, 1045.278757657214, 1066.328213225663, 1071.8231491803922, 18911.054447277307, 19181.520461988563, 19627.214911403385, 19667.21331146738, 19780.208791648172, 50978.19512340279, 51213.74393128011, 51579.22631160611, 51774.741126294124, 53135.93728125493, 61733.69133154306, 76965.61517192378, 77414.0, 77925.61037194778, 82491.58754206191, 6297.433231009231, 8287.461315014505, 8464.915350846413, 8507.276881465144, 8598.570071496526, 11395.487203075716, 12966.546170883974, 13413.18323152149, 13423.798643020558, 13668.589942301747, 9844.803103938024, 11621.477033533341, 11643.359615221245, 11903.40482975865, 12023.579174728842, 11408.714782130513, 11652.951234388946, 11657.591984280509, 11675.824862627238, 11887.28676279426, 11915.582954596548, 11927.940360298144, 11931.343776092399, 12164.0, 12229.449674764835, 6822.863542729018, 8213.178682131978, 8335.416520843726, 8364.0, 8656.26421754158, 11708.531658733555, 11774.3524106175, 11967.042636589225, 12058.698532536406, 12068.698282543006], "value": 9975.956109446497, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1445.8337291211515, 1592.0686398456903, 1597.5127586086246, 1818.15455813047, 1943.9125239364228, 7872.803179920497, 8611.827763444735, 8802.07578204289, 8813.162749538784, 8822.206001459866, 8421.536815475147, 8654.177853103945, 8658.00432950211, 8658.134186581354, 8664.176903194202, 2166.3825809644254, 2408.4460574067975, 2437.695288088989, 2467.481828815951, 2480.379905023744, 2473.4434752180705, 2499.8125140614434, 2504.3864253257975, 2507.0849140063838, 2519.2945975126995, 2404.3868813452546, 2450.068973789966, 2451.2278632230878, 2454.8772561371948, 2460.3111128883943, 2144.742630884296, 2381.3927448500694, 2431.0275889644195, 2457.1154384421593, 2457.668214791016, 2442.255112190781, 2466.580681284186, 2470.6170543565595, 2482.1808803094937, 2492.3644470660047, 8632.007319158314, 8687.826243475096, 8691.652333906635, 8711.259542938858, 8719.564021798884, 5149.407818100892, 5360.437154098841, 5383.650062745909, 5387.488188622114, 5398.946010539923, 3436.6563343665684, 3505.106197919514, 3604.7476676632646, 3615.3130905128205, 3633.8364773585254, 1021.0759262867117, 1043.8956104389576, 1045.63402809017, 1048.8217003109414, 1076.3272954403499, 11519.48162332697, 11616.477258523386, 11630.592929247521, 11632.360220187886, 11634.883651163549, 4544.636429085665, 4576.4737055239075, 4673.859784206479, 4695.295705644113, 4705.976470117627, 9248.0, 9261.351705380559, 9273.768155796024, 9277.628894844265, 9288.303377246599, 5286.207068939613, 5289.735513224324, 5322.201669749568, 5323.22813192085, 5326.7869285228935, 4243.745375277494, 4448.332750087448, 4540.182767101954, 4572.588467037916, 4575.450945886449, 3272.5582046423906, 3828.8468461261236, 3844.8269827857252, 3909.179072394829, 3940.7438516496336, 1210.2980271442543, 1219.2501611508858, 1223.473906220334, 1234.0806099455963, 1234.7530493901256, 831.2061980808313, 854.9145085491401, 897.9146981036791, 909.6088681866861, 913.5295322908707, 5481.862953426196, 5733.971330143406, 5739.196512488255, 5907.881842363128, 5943.019401798655, 944.617429940875, 947.3368641950663, 956.6651671914774, 970.9465979371203, 982.4891056650501, 3756.718246131551, 3784.4323351496946, 3882.378819388941, 3920.8039598019986, 3991.002249437641, 10312.278140530241, 10602.992715691997, 10705.411202383788, 10712.46437678113, 10897.67306980792, 10615.628453004108, 10723.94638026805, 10747.408892510986, 10753.83869241962, 10786.94606526978, 6690.899636505456, 7943.880841787378, 7991.44059915812, 8199.508029518249, 8310.376721745894, 5142.357205349332, 5673.149027645886, 5765.0, 5797.188393624811, 5830.504407125272, 9364.438133712, 9813.509324533887, 9826.115649591571, 9931.155851752394, 9981.700548983252, 9933.503324833873, 10190.337628054449, 10193.082622564005, 10227.795444090927, 10310.639127630497, 6535.3464653534265, 6542.901856472252, 7004.894926576208, 7110.39561637246, 7116.537425067561, 8312.33501319905, 8334.6666133354, 8375.371847111614, 8393.874091888505, 8398.748037558884, 7334.816629584303, 7449.255074492507, 7508.812279693051, 7593.012908321883, 7632.045994250719, 7203.02759127532, 7280.271972802678, 7281.417486600953, 7413.518121322097, 7465.776026719207, 7030.191527974348, 7088.929110709033, 7118.359347658736, 7123.180834204133, 7128.429725621834, 4743.715377077387, 4846.36997190363, 4887.951120488892, 4896.583790377716, 4927.211646136601, 4246.150769846105, 4898.92651610219, 5071.18860982241, 5093.23601459784, 5234.13636749932], "value": 4555.460539302283, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [320.04147578003875, 321.62530651790667, 328.2483113669696, 329.4547523848032, 333.36993083073014, 5278.6568873023225, 5452.773126046647, 5476.17857321402, 5488.255872063968, 5515.227868098465, 5365.678059316444, 5401.216823560581, 5404.864878378033, 5407.324084489439, 5428.511433970942, 3958.940615890764, 4005.7596544207295, 4075.6535694466006, 4184.761152783261, 4254.97872510637, 3643.908902277438, 3743.9812800936093, 3929.6659783918403, 4233.348993892384, 4234.586285817086, 3551.827896794052, 3986.222686576119, 4128.731632443882, 4149.336106223005, 4167.791610419482, 3270.9018729438153, 3468.6357932416986, 3987.4417581538464, 4170.874873753793, 4170.895727606804, 3043.8478076096108, 3173.9525956434322, 3189.8405079746153, 3629.618890016563, 3650.653187947141, 4566.817327306904, 5194.961007798455, 5202.745227320396, 5258.0, 5263.894722105575, 4144.647704945083, 4390.495093064306, 4507.504174540798, 4691.545620857511, 4839.201531747258, 5676.716164191774, 5682.318121825408, 5686.488216060533, 5698.6580805151825, 5703.629264097862, 3914.432407300925, 3914.8825535233987, 3925.9018524536827, 3934.449177115206, 3940.7832569208967, 5626.621477737939, 5888.352281249059, 6004.609700369506, 6025.559648342429, 6052.818415447544, 3387.6612338765926, 3570.928581428409, 3915.882523524299, 3961.9009524761536, 4006.659433948089, 3436.350551735181, 4074.553533495566, 4078.3882417636996, 4081.4081958115908, 4090.6727461802984, 4039.3940908863906, 4510.892865062514, 4582.900103975022, 4731.503192164842, 4737.620990320766, 5231.790728370823, 5561.610687251854, 5760.423957604206, 5786.218860453785, 5787.652740835563, 1459.3870574358796, 1474.8820094392422, 1476.6751314710746, 1483.9629009274854, 1484.9851501485064, 906.5875026862761, 927.1979737527125, 958.5063692198523, 965.6234068713208, 982.3271059324386, 5726.856828579319, 5733.88532229353, 5740.856478588069, 5749.470233097424, 5773.769049237983, 5327.188755823039, 6382.968085159545, 6389.137466442064, 6390.488760899117, 6458.870822583522, 4357.281048626941, 4753.667243292936, 4937.876553086202, 4973.129702302155, 5015.3480047593575, 2810.889698569054, 3366.377220214268, 3367.7979321240805, 3389.67798059184, 3398.456247000468, 5445.747478080064, 6129.751231847128, 6349.0, 6745.9662701686175, 6751.392374686302, 6049.939500604937, 6060.393960603904, 6521.673916304166, 6618.536702430783, 6626.171728533934, 6582.407583317525, 6643.7674681385915, 6705.826480366014, 6733.754255462755, 6749.2238392584495, 6353.301136875032, 6455.580387274812, 6459.967700161469, 6460.67696615158, 6500.512461565496, 6279.3720627938665, 6337.9366206337345, 6338.366163383625, 6357.523185761179, 6384.425401713868, 5875.29736321297, 5998.34018258, 6402.7194561087035, 6715.56348837324, 6716.0, 4204.600562946515, 4208.179405016115, 4232.703710740218, 4239.342901850218, 4242.109157076925, 5423.5932305076485, 6189.752409903554, 6454.837129806455, 6495.350464953467, 6514.804555863332, 5349.732513374393, 5681.863627274479, 6068.544859135671, 6264.279607844974, 6285.779997700059, 6233.532485063548, 6235.781747638811, 6238.0, 6269.279032911274, 6293.496520278458, 5885.0, 6077.4226448487325, 6082.756689732538, 6098.847528811815, 6114.755409783559, 5051.777218024057, 5238.0833354162105, 5943.465088142088, 6220.595661282002, 6276.0, 5886.94113058864, 5989.64062156272, 6150.0, 6166.753329866755, 6194.225721784777], "value": 4356.574465916908, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [663.3267233757736, 730.4302643937731, 736.6611358774963, 745.8955746195534, 754.4379437319199, 12640.620781376561, 14871.702565948675, 15140.167290799016, 16661.00033997961, 17257.30970761168, 15576.376944922189, 15646.0, 15743.763843542356, 15754.212289385543, 15774.0, 721.8051126195924, 745.4185735126609, 749.2208103572278, 925.4539821505293, 932.5663566441604, 756.5082696247456, 759.6961215513784, 761.2007392238128, 898.4294972692355, 942.0955882352949, 982.6511588386127, 989.0356902020544, 990.1336330710628, 991.3605724307818, 992.1071036067561, 979.2900147393154, 982.670805280232, 982.9262805289632, 983.5033308179395, 984.0700537991601, 568.5622071005303, 706.3642721550594, 729.580491217552, 730.9342159205697, 735.2500449541491, 15233.466828661054, 15285.694286114216, 15297.770533442008, 15311.693766124616, 15338.07971521701, 10158.847617285746, 12354.876451235463, 12446.0, 12587.433565489575, 12839.229646221256, 10060.798784024279, 10114.494275286206, 10170.338927969658, 10361.585536578528, 10432.478376081166, 3439.914002149966, 3459.705924996378, 3590.6947909427467, 3595.280943811222, 3596.3886139356373, 70865.22836929117, 73649.0, 73667.26332736657, 73683.89474157892, 73798.26201737967, 9402.623895044258, 9555.617775289049, 9718.562664680107, 9767.120959113714, 9830.066143716336, 20443.28448504325, 20489.692654610193, 20531.794682052987, 20534.897325513575, 20540.589188216152, 7267.091613548307, 7274.272572742683, 7539.246075392527, 7583.9620801895635, 7702.614869256627, 4035.3946907963455, 4085.4893138357706, 4100.34394496879, 4135.29699951009, 4295.871123866289, 2962.4519463899246, 3507.9123021924147, 3508.4035713928693, 3521.454174602941, 3565.4117070683046, 610.5512448350547, 634.8508100596356, 635.123529529256, 635.3329004545209, 650.2879347114902, 536.3590509341377, 578.0721941284262, 584.0654952076643, 599.5263741644052, 608.5831205624162, 7295.489315747953, 7733.806654833674, 8101.432899696964, 8156.551389673625, 8193.221643943816, 513.6507175120954, 575.8358867722762, 596.3440215762713, 597.1490625858135, 610.1305639463747, 3179.459491886385, 3764.962350376516, 3768.5854555998817, 3815.809209539512, 3986.940195897064, 88463.55768221247, 90606.54696726605, 91420.0, 91946.62080068805, 97107.51446242724, 72645.63677181686, 79368.603156985, 81690.59154704188, 83048.58475707583, 89791.55104224438, 73703.0, 73988.0, 74121.0, 74278.0, 75024.24975750282, 8156.388270879826, 8160.061592916891, 8216.972878390201, 8383.395140089577, 8415.5792210388, 45415.77292113518, 45629.7525784539, 45688.771556142005, 46807.0, 47381.81545461391, 59996.0, 62398.0, 63136.684316578125, 65174.67412662906, 65577.34422655712, 10459.68620941373, 10696.946515267375, 11255.118673716905, 11618.49194772711, 11636.476358564054, 10460.843087353544, 11673.007794337576, 11708.414579270831, 12130.393480325773, 12397.75204495923, 7185.856282874209, 11287.435628218393, 11787.469563869818, 11912.166148369532, 11921.52313907434, 10757.0, 11183.72040698989, 11658.76682466329, 11900.642980710592, 11968.16222864391, 11288.492017859051, 11340.319580825177, 11561.42192890369, 11613.0, 11649.301041937511, 9663.323567350499, 9878.851817222603, 9887.950560247153, 9983.400995940267, 10267.691969240936, 8679.739607811776, 9076.546172691471, 9543.618255269988, 9546.761330966503, 9685.612575497184], "value": 6841.944297669479, "unit": "score"}], "name": "dromaeo_css", "value": 7338.968147757714, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 109.0, 113.0, 114.0, 115.0, 114.0, 115.0, 104.0, 115.0], "value": 112.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 147.0, 143.0, 206.0, 153.0, 174.0, 165.0, 117.0, 168.0, 118.0], "value": 156.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 187.0, 187.0, 185.0, 185.0, 184.0, 184.0, 191.0, 186.0, 181.0], "value": 185.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 77.0, 76.0, 151.0, 56.0, 54.0, 59.0, 66.0, 133.0, 57.0], "value": 80.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 141.0, 78.0, 84.0, 92.0, 123.0, 79.0, 80.0, 132.0], "value": 98.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 88.0, 86.0, 88.0, 87.0, 136.0, 126.0, 88.0, 101.0], "value": 97.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 93.0, 93.0, 94.0, 93.0, 94.0, 92.0, 93.0], "value": 93.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 90.0, 84.0, 71.0, 127.0, 134.0, 108.0, 86.0, 84.0, 85.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 64.0, 66.0, 67.0, 65.0, 65.0, 63.0, 82.0, 60.0, 58.0], "value": 65.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 65.0, 64.0, 77.0, 77.0, 71.0, 69.0, 69.0, 67.0, 67.0], "value": 68.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 80.0, 80.0, 80.0, 81.0, 80.0, 78.0, 81.0, 85.0], "value": 80.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 136.0, 134.0, 137.0, 133.0, 133.0, 133.0, 135.0, 181.0, 136.0], "value": 139.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 168.0, 163.0, 168.0, 175.0, 168.0, 172.0, 166.0, 169.0, 167.0], "value": 168.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 68.0, 69.0, 68.0, 69.0, 68.0, 68.0, 68.0, 67.0], "value": 68.0, "unit": "score"}], "name": "kraken", "value": 1508.9, "alertThreshold": 2.0}]} 12:52:27 INFO - Return code: 0 12:52:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 12:52:27 ERROR - # TBPL SUCCESS # 12:52:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:52:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:52:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:52:27 INFO - 2016-05-05 12:52:27.097 screenresolution[1676:9855] starting screenresolution argv=screenresolution get 12:52:27 INFO - 2016-05-05 12:52:27.099 screenresolution[1676:9855] Display 0: 1600x1200x32@60 12:52:27 INFO - 2016-05-05 12:52:27.105 screenresolution[1677:9858] starting screenresolution argv=screenresolution list 12:52:27 INFO - Available Modes on Display 0 12:52:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:52:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:52:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:52:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:52:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:52:27 INFO - Intel Iris: 12:52:27 INFO - Chipset Model: Intel Iris 12:52:27 INFO - Type: GPU 12:52:27 INFO - Bus: Built-In 12:52:27 INFO - VRAM (Dynamic, Max): 1536 MB 12:52:27 INFO - Vendor: Intel (0x8086) 12:52:27 INFO - Device ID: 0x0a2e 12:52:27 INFO - Revision ID: 0x0009 12:52:27 INFO - Displays: 12:52:27 INFO - Display: 12:52:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:52:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:52:27 INFO - Display Serial Number: 1600x1200 60 12:52:27 INFO - Main Display: Yes 12:52:27 INFO - Mirror: Off 12:52:27 INFO - Online: Yes 12:52:27 INFO - Rotation: Supported 12:52:27 INFO - Return code: 0 12:52:27 INFO - Running post-action listener: _resource_record_post_action 12:52:27 INFO - Running post-run listener: _resource_record_post_run 12:52:28 INFO - Total resource usage - Wall time: 1208s; CPU: 53.0%; Read bytes: 48925184; Write bytes: 661571584; Read time: 562; Write time: 2500 12:52:28 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 344828416; Write bytes: 345941504; Read time: 26595; Write time: 649 12:52:28 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 47970816; Write bytes: 305676800; Read time: 546; Write time: 1822 12:52:28 INFO - Running post-run listener: _upload_blobber_files 12:52:28 INFO - Blob upload gear active. 12:52:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:52:28 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:52:28 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:52:28 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:52:28 INFO - (blobuploader) - INFO - Open directory for files ... 12:52:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:52:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:52:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:52:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:52:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:52:30 INFO - (blobuploader) - INFO - Done attempting. 12:52:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:52:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:52:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:52:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:52:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:52:31 INFO - (blobuploader) - INFO - Done attempting. 12:52:31 INFO - (blobuploader) - INFO - Iteration through files over. 12:52:31 INFO - Return code: 0 12:52:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:52:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:52:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3273b7df19b6d663f798eaab2cb12befe6e85a7b3f9d584a0703c9cc66053902b1b68a0041dd1680c852269b99257bc14a3a755b94f59e79042308f6c04aed20", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f6e01afe9c0a4fb7e4dcee161a1bf2808cf9a9cf7b385f2140c2874e40670a6b8b7ff7cb3a3b80297745e4de613c25ef657349abb904ce58cc9d63edbff48a3f"} 12:52:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:52:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:52:31 INFO - Contents: 12:52:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3273b7df19b6d663f798eaab2cb12befe6e85a7b3f9d584a0703c9cc66053902b1b68a0041dd1680c852269b99257bc14a3a755b94f59e79042308f6c04aed20", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f6e01afe9c0a4fb7e4dcee161a1bf2808cf9a9cf7b385f2140c2874e40670a6b8b7ff7cb3a3b80297745e4de613c25ef657349abb904ce58cc9d63edbff48a3f"} 12:52:31 INFO - Running post-run listener: copy_logs_to_upload_dir 12:52:31 INFO - Copying logs to upload dir... 12:52:31 INFO - mkdir: /builds/slave/test/build/upload/logs 12:52:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.831037 ========= master_lag: 0.14 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-05-05 12:52:31.181329) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-05 12:52:31.184971) ========= 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.AobOWwxtbW/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.rPx3C0vPJE/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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/3273b7df19b6d663f798eaab2cb12befe6e85a7b3f9d584a0703c9cc66053902b1b68a0041dd1680c852269b99257bc14a3a755b94f59e79042308f6c04aed20", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f6e01afe9c0a4fb7e4dcee161a1bf2808cf9a9cf7b385f2140c2874e40670a6b8b7ff7cb3a3b80297745e4de613c25ef657349abb904ce58cc9d63edbff48a3f"} build_url:https://queue.taskcluster.net/v1/task/F775xk7JQDyh51wKVQaHbg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011032 build_url: 'https://queue.taskcluster.net/v1/task/F775xk7JQDyh51wKVQaHbg/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/3273b7df19b6d663f798eaab2cb12befe6e85a7b3f9d584a0703c9cc66053902b1b68a0041dd1680c852269b99257bc14a3a755b94f59e79042308f6c04aed20", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f6e01afe9c0a4fb7e4dcee161a1bf2808cf9a9cf7b385f2140c2874e40670a6b8b7ff7cb3a3b80297745e4de613c25ef657349abb904ce58cc9d63edbff48a3f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-05 12:52:31.216768) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 12:52:31.217102) ========= 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.AobOWwxtbW/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.rPx3C0vPJE/Listeners TMPDIR=/var/folders/bz/5pkfnsh516b43lwcn2l8vvq400000w/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.005127 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 12:52:31.268215) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-05 12:52:31.268917) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-05 12:52:31.269602) ========= ========= Total master_lag: 0.27 =========