builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0146 starttime: 1462582496.88 results: success (0) buildid: 20160506160946 builduid: 209459d8cf2747b3b591f0d202dc4a09 revision: 0f66fd75014070ca82ddb8867bb85152d3d42484 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 17:54:56.876111) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 17:54:56.876556) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 17:54:56.876825) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 17:54:56.894189) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 17:54:56.894550) ========= 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.MPS3W5BrcD/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.F4I4fGSkig/Listeners TMPDIR=/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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-06 17:54:56-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 152M=0s 2016-05-06 17:54:57 (152 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.110347 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 17:54:57.021374) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 17:54:57.021691) ========= 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.MPS3W5BrcD/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.F4I4fGSkig/Listeners TMPDIR=/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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.020503 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 17:54:57.070158) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-06 17:54:57.070524) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 0f66fd75014070ca82ddb8867bb85152d3d42484 --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 0f66fd75014070ca82ddb8867bb85152d3d42484 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.MPS3W5BrcD/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.F4I4fGSkig/Listeners TMPDIR=/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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-06 17:54:57,143 truncating revision to first 12 chars 2016-05-06 17:54:57,143 Setting DEBUG logging. 2016-05-06 17:54:57,143 attempt 1/10 2016-05-06 17:54:57,143 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/0f66fd750140?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-06 17:54:58,443 unpacking tar archive at: mozilla-aurora-0f66fd750140/testing/mozharness/ program finished with exit code 0 elapsedTime=1.553025 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-06 17:54:58.640314) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 17:54:58.640732) ========= script_repo_revision: 0f66fd75014070ca82ddb8867bb85152d3d42484 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 17:54:58.641100) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 17:54:58.641374) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 17:54:58.654502) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-05-06 17:54:58.654805) ========= /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.MPS3W5BrcD/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.F4I4fGSkig/Listeners TMPDIR=/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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 17:54:58 INFO - MultiFileLogger online at 20160506 17:54:58 in /builds/slave/test 17:54:58 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 17:54:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:54:58 INFO - {'append_to_log': False, 17:54:58 INFO - 'base_work_dir': '/builds/slave/test', 17:54:58 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 17:54:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:54:58 INFO - 'branch': 'Mozilla-Aurora', 17:54:58 INFO - 'buildbot_json_path': 'buildprops.json', 17:54:58 INFO - 'config_files': ('talos/mac_config.py',), 17:54:58 INFO - 'default_actions': ('clobber', 17:54:58 INFO - 'read-buildbot-config', 17:54:58 INFO - 'download-and-extract', 17:54:58 INFO - 'populate-webroot', 17:54:58 INFO - 'create-virtualenv', 17:54:58 INFO - 'install', 17:54:58 INFO - 'run-tests'), 17:54:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:54:58 INFO - 'download_minidump_stackwalk': True, 17:54:58 INFO - 'download_symbols': 'ondemand', 17:54:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:54:58 INFO - 'tooltool.py': '/tools/tooltool.py', 17:54:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:54:58 INFO - '/tools/misc-python/virtualenv.py')}, 17:54:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:54:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:54:58 INFO - 'installer_path': 'installer.exe', 17:54:58 INFO - 'log_level': 'info', 17:54:58 INFO - 'log_name': 'talos', 17:54:58 INFO - 'log_to_console': True, 17:54:58 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:54:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:54:58 INFO - 'opt_config_files': (), 17:54:58 INFO - 'pip_index': False, 17:54:58 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:54:58 INFO - 'cmd': ('bash', 17:54:58 INFO - '-c', 17:54:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:54:58 INFO - 'enabled': True, 17:54:58 INFO - 'halt_on_failure': False, 17:54:58 INFO - 'name': 'check_screen_resolution'},), 17:54:58 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:54:58 INFO - 'cmd': ('bash', 17:54:58 INFO - '-c', 17:54:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:54:58 INFO - 'enabled': True, 17:54:58 INFO - 'halt_on_failure': False, 17:54:58 INFO - 'name': 'check_screen_resolution'},), 17:54:58 INFO - 'run_cmd_checks_enabled': True, 17:54:58 INFO - 'sps_profile': False, 17:54:58 INFO - 'sps_profile_interval': 0, 17:54:58 INFO - 'suite': 'dromaeojs', 17:54:58 INFO - 'system_bits': '32', 17:54:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:54:58 INFO - 'title': 't-yosemite-r7-0146', 17:54:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:54:58 INFO - 'use_talos_json': True, 17:54:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:54:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:54:58 INFO - 'work_dir': 'build'} 17:54:58 INFO - ##### 17:54:58 INFO - ##### Running clobber step. 17:54:58 INFO - ##### 17:54:58 INFO - Running pre-action listener: _resource_record_pre_action 17:54:58 INFO - Running main action method: clobber 17:54:58 INFO - rmtree: /builds/slave/test/build 17:54:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:55:00 INFO - Running post-action listener: _resource_record_post_action 17:55:00 INFO - ##### 17:55:00 INFO - ##### Running read-buildbot-config step. 17:55:00 INFO - ##### 17:55:00 INFO - Running pre-action listener: _resource_record_pre_action 17:55:00 INFO - Running main action method: read_buildbot_config 17:55:00 INFO - Using buildbot properties: 17:55:00 INFO - { 17:55:00 INFO - "project": "", 17:55:00 INFO - "product": "firefox", 17:55:00 INFO - "script_repo_revision": "production", 17:55:00 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 17:55:00 INFO - "repository": "", 17:55:00 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 17:55:00 INFO - "buildid": "20160506160946", 17:55:00 INFO - "pgo_build": "False", 17:55:00 INFO - "basedir": "/builds/slave/test", 17:55:00 INFO - "buildnumber": 4, 17:55:00 INFO - "slavename": "t-yosemite-r7-0146", 17:55:00 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 17:55:00 INFO - "platform": "yosemite_r7", 17:55:00 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 17:55:00 INFO - "branch": "mozilla-aurora", 17:55:00 INFO - "repo_path": "releases/mozilla-aurora", 17:55:00 INFO - "revision": "0f66fd75014070ca82ddb8867bb85152d3d42484", 17:55:00 INFO - "stage_platform": "macosx64", 17:55:00 INFO - "builduid": "209459d8cf2747b3b591f0d202dc4a09", 17:55:00 INFO - "slavebuilddir": "test" 17:55:00 INFO - } 17:55:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/YHZWK5nvTKuinnqUdxFopg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 17:55:00 INFO - Running post-action listener: _resource_record_post_action 17:55:00 INFO - ##### 17:55:00 INFO - ##### Running download-and-extract step. 17:55:00 INFO - ##### 17:55:00 INFO - Running pre-action listener: _resource_record_pre_action 17:55:00 INFO - Running main action method: download_and_extract 17:55:00 INFO - mkdir: /builds/slave/test/build/tests 17:55: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')]} 17:55:00 INFO - https://queue.taskcluster.net/v1/task/YHZWK5nvTKuinnqUdxFopg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 17:55:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YHZWK5nvTKuinnqUdxFopg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 17:55:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YHZWK5nvTKuinnqUdxFopg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 17:55:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YHZWK5nvTKuinnqUdxFopg/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 17:55:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YHZWK5nvTKuinnqUdxFopg/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 17:55:02 INFO - Downloaded 1199 bytes. 17:55:02 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 17:55:02 INFO - Using the following test package requirements: 17:55:02 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 17:55:02 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 17:55:02 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 17:55:02 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 17:55:02 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 17:55:02 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 17:55:02 INFO - u'jsshell-mac.zip'], 17:55:02 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 17:55:02 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 17:55:02 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 17:55:02 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 17:55:02 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 17:55:02 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 17:55:02 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 17:55:02 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 17:55:02 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 17:55:02 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 17:55:02 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 17:55:02 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 17:55:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:55:02 INFO - https://queue.taskcluster.net/v1/task/YHZWK5nvTKuinnqUdxFopg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 17:55:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YHZWK5nvTKuinnqUdxFopg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 17:55:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YHZWK5nvTKuinnqUdxFopg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 17:55:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YHZWK5nvTKuinnqUdxFopg/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 17:55:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YHZWK5nvTKuinnqUdxFopg/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 17:55:03 INFO - Downloaded 18088856 bytes. 17:55:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:55:03 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 17:55:06 INFO - Return code: 0 17:55:06 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 17:55:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:55:06 INFO - https://queue.taskcluster.net/v1/task/YHZWK5nvTKuinnqUdxFopg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 17:55:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YHZWK5nvTKuinnqUdxFopg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 17:55:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YHZWK5nvTKuinnqUdxFopg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 17:55:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YHZWK5nvTKuinnqUdxFopg/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 17:55:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YHZWK5nvTKuinnqUdxFopg/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 17:55:07 INFO - Downloaded 18088856 bytes. 17:55:07 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'] 17:55:07 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 17:55:11 INFO - Return code: 0 17:55:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:55:11 INFO - https://queue.taskcluster.net/v1/task/YHZWK5nvTKuinnqUdxFopg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 17:55:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YHZWK5nvTKuinnqUdxFopg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 17:55:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YHZWK5nvTKuinnqUdxFopg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 17:55:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YHZWK5nvTKuinnqUdxFopg/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 17:55:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YHZWK5nvTKuinnqUdxFopg/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 17:55:12 INFO - Downloaded 10840145 bytes. 17:55:12 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'] 17:55:12 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 17:55:12 INFO - Return code: 0 17:55:12 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')]} 17:55:12 INFO - https://queue.taskcluster.net/v1/task/YHZWK5nvTKuinnqUdxFopg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 17:55:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YHZWK5nvTKuinnqUdxFopg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 17:55:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YHZWK5nvTKuinnqUdxFopg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 17:55:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YHZWK5nvTKuinnqUdxFopg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 17:55:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/YHZWK5nvTKuinnqUdxFopg/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 17:55:15 INFO - Downloaded 114086552 bytes. 17:55:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YHZWK5nvTKuinnqUdxFopg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 17:55:15 INFO - mkdir: /builds/slave/test/properties 17:55:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:55:15 INFO - Writing to file /builds/slave/test/properties/build_url 17:55:15 INFO - Contents: 17:55:15 INFO - build_url:https://queue.taskcluster.net/v1/task/YHZWK5nvTKuinnqUdxFopg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 17:55:16 INFO - Running post-action listener: _resource_record_post_action 17:55:16 INFO - Running post-action listener: set_extra_try_arguments 17:55:16 INFO - ##### 17:55:16 INFO - ##### Running populate-webroot step. 17:55:16 INFO - ##### 17:55:16 INFO - Running pre-action listener: _resource_record_pre_action 17:55:16 INFO - Running main action method: populate_webroot 17:55:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:55:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:55:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:55:16 INFO - '--tppagecycles', 17:55:16 INFO - '7'], 17:55:16 INFO - 'tests': ['tsvgm']}}, 17:55:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:55:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:55:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:55:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:55:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:55:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:55:16 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:55:16 INFO - 'talos_options': ['--disable-e10s'], 17:55:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:55:16 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:55:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:55:16 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:55:16 INFO - 'talos_options': ['--disable-e10s'], 17:55:16 INFO - 'tests': ['damp', 'tps']}, 17:55:16 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:55:16 INFO - 'tests': ['damp', 'tps']}, 17:55:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:55:16 INFO - 'tests': ['dromaeo_dom']}, 17:55:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:55:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:55:16 INFO - 'tests': ['a11yr', 17:55:16 INFO - 'ts_paint', 17:55:16 INFO - 'tpaint', 17:55:16 INFO - 'sessionrestore', 17:55:16 INFO - 'sessionrestore_no_auto_restore', 17:55:16 INFO - 'tabpaint']}, 17:55:16 INFO - 'other-e10s': {'tests': ['a11yr', 17:55:16 INFO - 'ts_paint', 17:55:16 INFO - 'tpaint', 17:55:16 INFO - 'sessionrestore', 17:55:16 INFO - 'sessionrestore_no_auto_restore', 17:55:16 INFO - 'tabpaint']}, 17:55:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:55:16 INFO - 'ts_paint', 17:55:16 INFO - 'tpaint', 17:55:16 INFO - 'sessionrestore', 17:55:16 INFO - 'sessionrestore_no_auto_restore', 17:55:16 INFO - 'tabpaint']}, 17:55:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:55:16 INFO - 'ts_paint', 17:55:16 INFO - 'tpaint', 17:55:16 INFO - 'sessionrestore', 17:55:16 INFO - 'sessionrestore_no_auto_restore', 17:55:16 INFO - 'tabpaint']}, 17:55:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:55:16 INFO - 'tests': ['a11yr', 17:55:16 INFO - 'ts_paint', 17:55:16 INFO - 'tpaint', 17:55:16 INFO - 'sessionrestore', 17:55:16 INFO - 'sessionrestore_no_auto_restore', 17:55:16 INFO - 'tabpaint']}, 17:55:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:55:16 INFO - 'tests': ['a11yr', 17:55:16 INFO - 'ts_paint', 17:55:16 INFO - 'tpaint', 17:55:16 INFO - 'sessionrestore', 17:55:16 INFO - 'sessionrestore_no_auto_restore', 17:55:16 INFO - 'tabpaint']}, 17:55:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:55:16 INFO - 'tests': ['tsvgx', 17:55:16 INFO - 'tsvgr_opacity', 17:55:16 INFO - 'tart', 17:55:16 INFO - 'tscrollx', 17:55:16 INFO - 'cart']}, 17:55:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:55:16 INFO - 'tsvgr_opacity', 17:55:16 INFO - 'tart', 17:55:16 INFO - 'tscrollx', 17:55:16 INFO - 'cart']}, 17:55:16 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:55:16 INFO - 'talos_options': ['--disable-e10s'], 17:55:16 INFO - 'tests': ['tp5o']}, 17:55:16 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:55:16 INFO - 'tests': ['tp5o']}, 17:55:16 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:55:16 INFO - 'talos_options': ['--disable-e10s', 17:55:16 INFO - '--xperf_path', 17:55:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:55:16 INFO - 'tests': ['tp5n']}, 17:55:16 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:55:16 INFO - 'talos_options': ['--xperf_path', 17:55:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:55:16 INFO - 'tests': ['tp5n']}}, 17:55:16 INFO - 'talos.zip': {'path': '', 17:55:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:55:16 INFO - Running post-action listener: _resource_record_post_action 17:55:16 INFO - ##### 17:55:16 INFO - ##### Running create-virtualenv step. 17:55:16 INFO - ##### 17:55:16 INFO - Running pre-action listener: _resource_record_pre_action 17:55:16 INFO - Running main action method: create_virtualenv 17:55:16 INFO - Creating virtualenv /builds/slave/test/build/venv 17:55:16 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 17:55:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:55:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:55:16 INFO - Using real prefix '/tools/python27' 17:55:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:55:17 INFO - Installing distribute.............................................................................................................................................................................................done. 17:55:20 INFO - Installing pip.................done. 17:55:20 INFO - Return code: 0 17:55:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:55:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:55:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:55:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:55:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:55:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:55:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:55:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109658a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109668420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febb2490480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094f7e00>, '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 0x109360db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094f2b20>, '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.MPS3W5BrcD/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.F4I4fGSkig/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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 17:55:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:55:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:55:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MPS3W5BrcD/Render', 17:55:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:55:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:55:20 INFO - 'HOME': '/Users/cltbld', 17:55:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:55:20 INFO - 'LOGNAME': 'cltbld', 17:55:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:55:20 INFO - 'MOZ_NO_REMOTE': '1', 17:55:20 INFO - 'NO_EM_RESTART': '1', 17:55:20 INFO - 'PAGER': '/bin/cat', 17:55:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:55:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:55:20 INFO - 'PWD': '/builds/slave/test', 17:55:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:55:20 INFO - 'SHELL': '/bin/bash', 17:55:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F4I4fGSkig/Listeners', 17:55:20 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 17:55:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:55:20 INFO - 'USER': 'cltbld', 17:55:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:55:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:55:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:55:20 INFO - 'XPC_FLAGS': '0x0', 17:55:20 INFO - 'XPC_SERVICE_NAME': '0', 17:55:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:55:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:55:20 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 17:55:20 INFO - Cleaning up... 17:55:20 INFO - Return code: 0 17:55:20 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 17:55:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:55:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:55:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:55:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:55:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:55:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:55:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109658a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109668420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febb2490480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094f7e00>, '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 0x109360db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094f2b20>, '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.MPS3W5BrcD/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.F4I4fGSkig/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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 17:55:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 17:55:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 17:55:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MPS3W5BrcD/Render', 17:55:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:55:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:55:20 INFO - 'HOME': '/Users/cltbld', 17:55:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:55:20 INFO - 'LOGNAME': 'cltbld', 17:55:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:55:20 INFO - 'MOZ_NO_REMOTE': '1', 17:55:20 INFO - 'NO_EM_RESTART': '1', 17:55:20 INFO - 'PAGER': '/bin/cat', 17:55:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:55:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:55:20 INFO - 'PWD': '/builds/slave/test', 17:55:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:55:20 INFO - 'SHELL': '/bin/bash', 17:55:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F4I4fGSkig/Listeners', 17:55:20 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 17:55:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:55:20 INFO - 'USER': 'cltbld', 17:55:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:55:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:55:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:55:20 INFO - 'XPC_FLAGS': '0x0', 17:55:20 INFO - 'XPC_SERVICE_NAME': '0', 17:55:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:55:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:55:20 INFO - Downloading/unpacking psutil>=0.7.1 17:55:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:55:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:55:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:55:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:55:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:55:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:55:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:55:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:55:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:55:23 INFO - Installing collected packages: psutil 17:55:23 INFO - Running setup.py install for psutil 17:55:23 INFO - building 'psutil._psutil_osx' extension 17:55:23 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 17:55:23 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 17:55:23 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 17:55:24 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 17:55:24 INFO - building 'psutil._psutil_posix' extension 17:55:24 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 17:55:24 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:55:24 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:55:24 INFO - ^ 17:55:24 INFO - 1 warning generated. 17:55:24 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 17:55:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:55:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:55:24 INFO - Successfully installed psutil 17:55:24 INFO - Cleaning up... 17:55:24 INFO - Return code: 0 17:55:24 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 17:55:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:55:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:55:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:55:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:55:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:55:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:55:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109658a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109668420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febb2490480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094f7e00>, '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 0x109360db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094f2b20>, '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.MPS3W5BrcD/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.F4I4fGSkig/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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 17:55:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 17:55:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 17:55:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MPS3W5BrcD/Render', 17:55:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:55:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:55:24 INFO - 'HOME': '/Users/cltbld', 17:55:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:55:24 INFO - 'LOGNAME': 'cltbld', 17:55:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:55:24 INFO - 'MOZ_NO_REMOTE': '1', 17:55:24 INFO - 'NO_EM_RESTART': '1', 17:55:24 INFO - 'PAGER': '/bin/cat', 17:55:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:55:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:55:24 INFO - 'PWD': '/builds/slave/test', 17:55:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:55:24 INFO - 'SHELL': '/bin/bash', 17:55:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F4I4fGSkig/Listeners', 17:55:24 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 17:55:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:55:24 INFO - 'USER': 'cltbld', 17:55:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:55:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:55:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:55:24 INFO - 'XPC_FLAGS': '0x0', 17:55:24 INFO - 'XPC_SERVICE_NAME': '0', 17:55:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:55:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:55:24 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 17:55:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:55:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:55:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:55:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:55:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:55:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:55:27 INFO - Downloading mozsystemmonitor-0.0.tar.gz 17:55:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:55:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 17:55:27 INFO - Installing collected packages: mozsystemmonitor 17:55:27 INFO - Running setup.py install for mozsystemmonitor 17:55:27 INFO - Successfully installed mozsystemmonitor 17:55:27 INFO - Cleaning up... 17:55:27 INFO - Return code: 0 17:55:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:55:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:55:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:55:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:55:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:55:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:55:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:55:27 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 0x109658a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109668420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febb2490480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094f7e00>, '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 0x109360db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094f2b20>, '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.MPS3W5BrcD/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.F4I4fGSkig/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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 17:55:27 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 17:55:27 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 17:55:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MPS3W5BrcD/Render', 17:55:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:55:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:55:27 INFO - 'HOME': '/Users/cltbld', 17:55:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:55:27 INFO - 'LOGNAME': 'cltbld', 17:55:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:55:27 INFO - 'MOZ_NO_REMOTE': '1', 17:55:27 INFO - 'NO_EM_RESTART': '1', 17:55:27 INFO - 'PAGER': '/bin/cat', 17:55:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:55:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:55:27 INFO - 'PWD': '/builds/slave/test', 17:55:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:55:27 INFO - 'SHELL': '/bin/bash', 17:55:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F4I4fGSkig/Listeners', 17:55:27 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 17:55:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:55:27 INFO - 'USER': 'cltbld', 17:55:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:55:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:55:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:55:27 INFO - 'XPC_FLAGS': '0x0', 17:55:27 INFO - 'XPC_SERVICE_NAME': '0', 17:55:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:55:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:55:27 INFO - Downloading/unpacking blobuploader==1.2.4 17:55:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:55:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:55:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:55:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:55:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:55:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:55:30 INFO - Downloading blobuploader-1.2.4.tar.gz 17:55:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:55:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:55:30 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 17:55:30 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 17:55:30 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 17:55:30 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 17:55:30 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 17:55:30 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 17:55:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:55:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:55:30 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 17:55:30 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 17:55:30 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 17:55:30 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 17:55:30 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 17:55:30 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 17:55:31 INFO - Downloading docopt-0.6.1.tar.gz 17:55:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:55:31 INFO - Installing collected packages: blobuploader, requests, docopt 17:55:31 INFO - Running setup.py install for blobuploader 17:55:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:55:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:55:31 INFO - Running setup.py install for requests 17:55:31 INFO - Running setup.py install for docopt 17:55:32 INFO - Successfully installed blobuploader requests docopt 17:55:32 INFO - Cleaning up... 17:55:32 INFO - Return code: 0 17:55:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:55:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:55:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:55:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:55:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:55:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:55:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:55:32 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 0x109658a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109668420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febb2490480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094f7e00>, '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 0x109360db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094f2b20>, '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.MPS3W5BrcD/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.F4I4fGSkig/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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 17:55:32 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 17:55:32 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 17:55:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MPS3W5BrcD/Render', 17:55:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:55:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:55:32 INFO - 'HOME': '/Users/cltbld', 17:55:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:55:32 INFO - 'LOGNAME': 'cltbld', 17:55:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:55:32 INFO - 'MOZ_NO_REMOTE': '1', 17:55:32 INFO - 'NO_EM_RESTART': '1', 17:55:32 INFO - 'PAGER': '/bin/cat', 17:55:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:55:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:55:32 INFO - 'PWD': '/builds/slave/test', 17:55:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:55:32 INFO - 'SHELL': '/bin/bash', 17:55:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F4I4fGSkig/Listeners', 17:55:32 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 17:55:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:55:32 INFO - 'USER': 'cltbld', 17:55:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:55:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:55:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:55:32 INFO - 'XPC_FLAGS': '0x0', 17:55:32 INFO - 'XPC_SERVICE_NAME': '0', 17:55:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:55:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:55:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:55:32 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-yyOi8T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:55:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:55:32 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-hzU6e0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:55:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:55:32 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-bwt9CT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:55:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:55:32 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-2cMjSr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:55:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:55:32 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-0W3PJw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:55:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:55:33 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-2lsVnb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:55:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:55:33 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-WxwKxn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:55:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:55:33 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-iy1AUD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:55:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:55:33 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-BOMb1Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:55:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:55:33 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-mZcYhj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:55:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:55:33 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-HH5jXZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:55:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:55:33 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-iaxO39-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:55:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:55:33 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-Spxe68-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:55:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:55:33 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-tjveuO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:55:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:55:33 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-4W0ppn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:55:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:55:34 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-WQ0YIQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:55:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:55:34 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-_GXaRF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:55:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:55:34 INFO - Running setup.py install for manifestparser 17:55:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:55:34 INFO - Running setup.py install for mozcrash 17:55:34 INFO - Running setup.py install for mozdebug 17:55:34 INFO - Running setup.py install for mozdevice 17:55:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:55:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:55:34 INFO - Running setup.py install for mozfile 17:55:35 INFO - Running setup.py install for mozhttpd 17:55:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:55:35 INFO - Running setup.py install for mozinfo 17:55:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:55:35 INFO - Running setup.py install for mozInstall 17:55:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:55:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:55:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:55:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:55:35 INFO - Running setup.py install for mozleak 17:55:35 INFO - Running setup.py install for mozlog 17:55:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:55:35 INFO - Running setup.py install for moznetwork 17:55:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:55:35 INFO - Running setup.py install for mozprocess 17:55:36 INFO - Running setup.py install for mozprofile 17:55:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:55:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:55:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:55:36 INFO - Running setup.py install for mozrunner 17:55:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:55:36 INFO - Running setup.py install for mozscreenshot 17:55:36 INFO - Running setup.py install for moztest 17:55:36 INFO - Running setup.py install for mozversion 17:55:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:55:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:55:36 INFO - Cleaning up... 17:55:36 INFO - Return code: 0 17:55:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:55:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:55:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:55:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:55:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:55:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:55:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:55:36 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 0x109658a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109668420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febb2490480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094f7e00>, '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 0x109360db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094f2b20>, '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.MPS3W5BrcD/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.F4I4fGSkig/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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 17:55:36 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 17:55:36 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 17:55:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MPS3W5BrcD/Render', 17:55:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:55:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:55:36 INFO - 'HOME': '/Users/cltbld', 17:55:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:55:36 INFO - 'LOGNAME': 'cltbld', 17:55:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:55:36 INFO - 'MOZ_NO_REMOTE': '1', 17:55:36 INFO - 'NO_EM_RESTART': '1', 17:55:36 INFO - 'PAGER': '/bin/cat', 17:55:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:55:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:55:36 INFO - 'PWD': '/builds/slave/test', 17:55:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:55:36 INFO - 'SHELL': '/bin/bash', 17:55:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F4I4fGSkig/Listeners', 17:55:36 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 17:55:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:55:36 INFO - 'USER': 'cltbld', 17:55:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:55:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:55:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:55:36 INFO - 'XPC_FLAGS': '0x0', 17:55:36 INFO - 'XPC_SERVICE_NAME': '0', 17:55:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:55:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:55:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:55:37 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-EoQKyk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:55:37 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)) 17:55:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:55:37 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-iln9gI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:55:37 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)) 17:55:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:55:37 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-LrUVYZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:55:37 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)) 17:55:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:55:37 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-WxM8x5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:55:37 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)) 17:55:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:55:37 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-Dclrr8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:55:37 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)) 17:55:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:55:37 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-84TsRr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:55:37 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)) 17:55:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:55:37 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-G8Ucba-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:55:37 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)) 17:55:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:55:37 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-z4ve3k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:55:37 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)) 17:55:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:55:37 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-163Ydk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:55:38 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)) 17:55:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:55:38 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-TvOFci-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:55:38 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)) 17:55:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:55:38 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-7GuKm1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:55:38 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)) 17:55:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:55:38 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-kVysUk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:55:38 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)) 17:55:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:55:38 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-mB756b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:55:38 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)) 17:55:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:55:38 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-_yvEzS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:55:38 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)) 17:55:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:55:38 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-L0h9Ft-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:55:38 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)) 17:55:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:55:38 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-uzHNC9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:55:38 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)) 17:55:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:55:38 INFO - Running setup.py (path:/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/pip-sG4JYh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:55:38 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)) 17:55:38 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)) 17:55:38 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)) 17:55:38 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)) 17:55:38 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)) 17:55:38 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)) 17:55:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:55:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:55:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:55:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:55:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:55:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:55:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:55:41 INFO - Downloading blessings-1.6.tar.gz 17:55:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:55:41 INFO - Installing collected packages: blessings 17:55:41 INFO - Running setup.py install for blessings 17:55:41 INFO - Successfully installed blessings 17:55:41 INFO - Cleaning up... 17:55:41 INFO - Return code: 0 17:55:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:55:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:55:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:55:42 INFO - Reading from file tmpfile_stdout 17:55:42 INFO - Current package versions: 17:55:42 INFO - blessings == 1.6 17:55:42 INFO - blobuploader == 1.2.4 17:55:42 INFO - docopt == 0.6.1 17:55:42 INFO - manifestparser == 1.1 17:55:42 INFO - mozInstall == 1.12 17:55:42 INFO - mozcrash == 0.17 17:55:42 INFO - mozdebug == 0.1 17:55:42 INFO - mozdevice == 0.48 17:55:42 INFO - mozfile == 1.2 17:55:42 INFO - mozhttpd == 0.7 17:55:42 INFO - mozinfo == 0.9 17:55:42 INFO - mozleak == 0.1 17:55:42 INFO - mozlog == 3.1 17:55:42 INFO - moznetwork == 0.27 17:55:42 INFO - mozprocess == 0.22 17:55:42 INFO - mozprofile == 0.28 17:55:42 INFO - mozrunner == 6.11 17:55:42 INFO - mozscreenshot == 0.1 17:55:42 INFO - mozsystemmonitor == 0.0 17:55:42 INFO - moztest == 0.7 17:55:42 INFO - mozversion == 1.4 17:55:42 INFO - psutil == 3.1.1 17:55:42 INFO - requests == 1.2.3 17:55:42 INFO - wsgiref == 0.1.2 17:55:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:55:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:55:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:55:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:55:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:55:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:55:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:55:42 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 0x109658a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109668420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febb2490480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094f7e00>, '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 0x109360db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094f2b20>, '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.MPS3W5BrcD/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.F4I4fGSkig/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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 17:55:42 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 17:55:42 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 17:55:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MPS3W5BrcD/Render', 17:55:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:55:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:55:42 INFO - 'HOME': '/Users/cltbld', 17:55:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:55:42 INFO - 'LOGNAME': 'cltbld', 17:55:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:55:42 INFO - 'MOZ_NO_REMOTE': '1', 17:55:42 INFO - 'NO_EM_RESTART': '1', 17:55:42 INFO - 'PAGER': '/bin/cat', 17:55:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:55:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:55:42 INFO - 'PWD': '/builds/slave/test', 17:55:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:55:42 INFO - 'SHELL': '/bin/bash', 17:55:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F4I4fGSkig/Listeners', 17:55:42 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 17:55:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:55:42 INFO - 'USER': 'cltbld', 17:55:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:55:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:55:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:55:42 INFO - 'XPC_FLAGS': '0x0', 17:55:42 INFO - 'XPC_SERVICE_NAME': '0', 17:55:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:55:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:55:42 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)) 17:55:42 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)) 17:55:42 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)) 17:55:42 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)) 17:55:42 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)) 17:55:42 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)) 17:55:42 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)) 17:55:42 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)) 17:55:42 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)) 17:55:42 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)) 17:55:42 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)) 17:55:42 INFO - Cleaning up... 17:55:42 INFO - Return code: 0 17:55:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:55:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:55:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:55:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:55:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:55:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:55:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:55:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109658a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109668420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febb2490480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094f7e00>, '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 0x109360db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094f2b20>, '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.MPS3W5BrcD/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.F4I4fGSkig/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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 17:55:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 17:55:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 17:55:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MPS3W5BrcD/Render', 17:55:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:55:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:55:42 INFO - 'HOME': '/Users/cltbld', 17:55:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:55:42 INFO - 'LOGNAME': 'cltbld', 17:55:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:55:42 INFO - 'MOZ_NO_REMOTE': '1', 17:55:42 INFO - 'NO_EM_RESTART': '1', 17:55:42 INFO - 'PAGER': '/bin/cat', 17:55:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:55:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:55:42 INFO - 'PWD': '/builds/slave/test', 17:55:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:55:42 INFO - 'SHELL': '/bin/bash', 17:55:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F4I4fGSkig/Listeners', 17:55:42 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 17:55:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:55:42 INFO - 'USER': 'cltbld', 17:55:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:55:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:55:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:55:42 INFO - 'XPC_FLAGS': '0x0', 17:55:42 INFO - 'XPC_SERVICE_NAME': '0', 17:55:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:55:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:55:42 INFO - Downloading/unpacking jsonschema 17:55:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:55:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:55:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:55:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:55:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:55:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:55:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:55:45 INFO - Downloading/unpacking functools32 (from jsonschema) 17:55:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:55:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:55:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:55:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:55:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:55:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:55:45 INFO - Downloading functools32-3.2.3-2.tar.gz 17:55:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:55:45 INFO - warning: no files found matching '*.txt' 17:55:45 INFO - no previously-included directories found matching 'build' 17:55:45 INFO - no previously-included directories found matching 'dist' 17:55:45 INFO - no previously-included directories found matching '.git*' 17:55:45 INFO - Installing collected packages: jsonschema, functools32 17:55:45 INFO - Running setup.py install for functools32 17:55:45 INFO - warning: no files found matching '*.txt' 17:55:45 INFO - no previously-included directories found matching 'build' 17:55:45 INFO - no previously-included directories found matching 'dist' 17:55:45 INFO - no previously-included directories found matching '.git*' 17:55:45 INFO - Successfully installed jsonschema functools32 17:55:45 INFO - Cleaning up... 17:55:45 INFO - Return code: 0 17:55:45 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 17:55:45 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')]} 17:55:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:55:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:55:45 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')]} 17:55:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:55:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:55:45 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 0x109658a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109668420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7febb2490480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1094f7e00>, '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 0x109360db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094f2b20>, '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.MPS3W5BrcD/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.F4I4fGSkig/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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 17:55:45 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 17:55:45 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 17:55:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MPS3W5BrcD/Render', 17:55:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:55:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:55:45 INFO - 'HOME': '/Users/cltbld', 17:55:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:55:45 INFO - 'LOGNAME': 'cltbld', 17:55:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:55:45 INFO - 'MOZ_NO_REMOTE': '1', 17:55:45 INFO - 'NO_EM_RESTART': '1', 17:55:45 INFO - 'PAGER': '/bin/cat', 17:55:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:55:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:55:45 INFO - 'PWD': '/builds/slave/test', 17:55:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:55:45 INFO - 'SHELL': '/bin/bash', 17:55:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F4I4fGSkig/Listeners', 17:55:45 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 17:55:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:55:45 INFO - 'USER': 'cltbld', 17:55:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:55:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:55:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:55:45 INFO - 'XPC_FLAGS': '0x0', 17:55:45 INFO - 'XPC_SERVICE_NAME': '0', 17:55:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:55:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:55:46 INFO - Downloading/unpacking flake8 17:55:46 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 17:55:46 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 17:55:46 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 17:55:46 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 17:55:46 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 17:55:46 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 17:55:48 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 17:55:48 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 17:55: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 17:55: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 17:55: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 17:55: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 17:55: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 17:55: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 17:55:49 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 17:55:49 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 17:55:49 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 17:55:49 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 17:55:49 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 17:55:49 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 17:55:49 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 17:55:49 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 17:55:49 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 17:55:49 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 17:55:49 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 17:55:49 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 17:55:49 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 17:55:49 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 17:55:49 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 17:55:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 17:55:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:55:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:55:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:55:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:55:49 INFO - no previously-included directories found matching 'docs/_build' 17:55:49 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 17:55:50 INFO - Running setup.py install for pep8 17:55:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:55:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:55:50 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:55:50 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:55:50 INFO - no previously-included directories found matching 'docs/_build' 17:55:50 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 17:55:50 INFO - Successfully installed flake8 mccabe pyflakes pep8 17:55:50 INFO - Cleaning up... 17:55:50 INFO - Return code: 0 17:55:50 INFO - Running post-action listener: _resource_record_post_action 17:55:50 INFO - Running post-action listener: _start_resource_monitoring 17:55:50 INFO - Starting resource monitoring. 17:55:50 INFO - ##### 17:55:50 INFO - ##### Running install step. 17:55:50 INFO - ##### 17:55:50 INFO - Running pre-action listener: _resource_record_pre_action 17:55:50 INFO - Running main action method: install 17:55:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:55:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:55:50 INFO - Reading from file tmpfile_stdout 17:55:50 INFO - Detecting whether we're running mozinstall >=1.0... 17:55:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:55:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:55:50 INFO - Reading from file tmpfile_stdout 17:55:50 INFO - Output received: 17:55:50 INFO - Usage: mozinstall [options] installer 17:55:50 INFO - Options: 17:55:50 INFO - -h, --help show this help message and exit 17:55:50 INFO - -d DEST, --destination=DEST 17:55:50 INFO - Directory to install application into. [default: 17:55:50 INFO - "/builds/slave/test"] 17:55:50 INFO - --app=APP Application being installed. [default: firefox] 17:55:50 INFO - mkdir: /builds/slave/test/build/application 17:55:50 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'] 17:55:50 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 17:56:22 INFO - Reading from file tmpfile_stdout 17:56:22 INFO - Output received: 17:56:22 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 17:56:22 INFO - Running post-action listener: _resource_record_post_action 17:56:22 INFO - ##### 17:56:22 INFO - ##### Running run-tests step. 17:56:22 INFO - ##### 17:56:22 INFO - Running pre-action listener: _resource_record_pre_action 17:56:22 INFO - Running main action method: run_tests 17:56:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:56:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:56:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:56:22 INFO - 2016-05-06 17:56:22.613 screenresolution[2478:29699] starting screenresolution argv=screenresolution get 17:56:22 INFO - 2016-05-06 17:56:22.616 screenresolution[2478:29699] Display 0: 1600x1200x32@60 17:56:22 INFO - 2016-05-06 17:56:22.622 screenresolution[2479:29702] starting screenresolution argv=screenresolution list 17:56:22 INFO - Available Modes on Display 0 17:56:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:56:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:56:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:56:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:56:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:56:22 INFO - Intel Iris: 17:56:22 INFO - Chipset Model: Intel Iris 17:56:22 INFO - Type: GPU 17:56:22 INFO - Bus: Built-In 17:56:22 INFO - VRAM (Dynamic, Max): 1536 MB 17:56:22 INFO - Vendor: Intel (0x8086) 17:56:22 INFO - Device ID: 0x0a2e 17:56:22 INFO - Revision ID: 0x0009 17:56:22 INFO - Displays: 17:56:22 INFO - Display: 17:56:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:56:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:56:22 INFO - Display Serial Number: 1600x1200 60 17:56:22 INFO - Main Display: Yes 17:56:22 INFO - Mirror: Off 17:56:22 INFO - Online: Yes 17:56:22 INFO - Rotation: Supported 17:56:22 INFO - Return code: 0 17:56:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:56:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:56:22 INFO - Python 2.7.3 17:56:22 INFO - Return code: 0 17:56:22 INFO - grabbing minidump binary from tooltool 17:56:22 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')]} 17:56:22 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 0x1094f7e00>, '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 0x109360db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1094f2b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:56:22 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 17:56:22 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 17:56:22 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:56:22 INFO - Return code: 0 17:56:22 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:56:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:56:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:56:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:56:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:56:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 17:56:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 17:56:24 INFO - Return code: 0 17:56:24 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/YHZWK5nvTKuinnqUdxFopg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0146', '--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 17:56:24 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/YHZWK5nvTKuinnqUdxFopg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0146 --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 17:56:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MPS3W5BrcD/Render', 17:56:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:56:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:56:24 INFO - 'HOME': '/Users/cltbld', 17:56:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:56:24 INFO - 'LOGNAME': 'cltbld', 17:56:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:56:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:56:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:56:24 INFO - 'MOZ_NO_REMOTE': '1', 17:56:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:56:24 INFO - 'NO_EM_RESTART': '1', 17:56:24 INFO - 'PAGER': '/bin/cat', 17:56:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:56:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:56:24 INFO - 'PWD': '/builds/slave/test', 17:56:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:56:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:56:24 INFO - 'SHELL': '/bin/bash', 17:56:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.F4I4fGSkig/Listeners', 17:56:24 INFO - 'TMPDIR': '/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/', 17:56:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:56:24 INFO - 'USER': 'cltbld', 17:56:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:56:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:56:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:56:24 INFO - 'XPC_FLAGS': '0x0', 17:56:24 INFO - 'XPC_SERVICE_NAME': '0', 17:56:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:56:24 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/YHZWK5nvTKuinnqUdxFopg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0146', '--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 17:56:24 INFO - mozversion application_buildid: 20160506160946 17:56:24 INFO - mozversion application_changeset: 0f66fd75014070ca82ddb8867bb85152d3d42484 17:56:24 INFO - mozversion application_display_name: FirefoxDeveloperEdition 17:56:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:56:24 INFO - mozversion application_name: Firefox 17:56:24 INFO - mozversion application_remotingname: firefox-dev 17:56:24 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 17:56:24 INFO - mozversion application_vendor: Mozilla 17:56:24 INFO - mozversion application_version: 48.0a2 17:56:24 INFO - mozversion platform_buildid: 20160506160946 17:56:24 INFO - mozversion platform_changeset: 0f66fd75014070ca82ddb8867bb85152d3d42484 17:56:24 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 17:56:24 INFO - mozversion platform_version: 48.0a2 17:56:24 INFO - using testdate: 1462582584 17:56:24 INFO - actual date: 1462582584 17:56:24 INFO - starting webserver on 'localhost:61759' 17:56:24 INFO - SUITE-START | Running 2 tests 17:56:24 INFO - TEST-START | dromaeo_css 17:56:24 INFO - operating with platform_type : mac_ 17:56:24 INFO - Initialising browser for dromaeo_css test... 17:56:24 INFO - TEST-INFO | started process 2497 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/tmp6D0y7Z/profile http://localhost:61759/getInfo.html) 17:56:26 INFO - PROCESS | 2497 | __metrics Screen width/height:1600/1200 17:56:26 INFO - PROCESS | 2497 | colorDepth:24 17:56:26 INFO - PROCESS | 2497 | Browser inner width/height: 1024/689 17:56:26 INFO - PROCESS | 2497 | __metrics 17:56:26 INFO - PROCESS | 2497 | 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" 17:56:26 ERROR - PROCESS | 2497 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 17:56:27 INFO - TEST-INFO | 2497: exit 0 17:56:27 INFO - Browser initialized. 17:56:27 INFO - Running cycle 1/1 for dromaeo_css test... 17:56:27 INFO - TEST-INFO | started process 2499 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/tmp6D0y7Z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:56:39 INFO - PROCESS | 2499 | RSS: Main: 211132416 17:56:39 INFO - PROCESS | 2499 | 17:57:30 INFO - PROCESS | 2499 | 1462582650684 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] 17:59:41 INFO - PROCESS | 2499 | Cycle 1(1): loaded http://localhost:61759/tests/dromaeo/cssquery-dojo.html (next: http://localhost:61759/tests/dromaeo/cssquery-ext.html) 17:59:42 INFO - PROCESS | 2499 | RSS: Main: 298024960 17:59:42 INFO - PROCESS | 2499 | 18:02:45 INFO - PROCESS | 2499 | Cycle 1(1): loaded http://localhost:61759/tests/dromaeo/cssquery-ext.html (next: http://localhost:61759/tests/dromaeo/cssquery-jquery.html) 18:02:46 INFO - PROCESS | 2499 | RSS: Main: 357621760 18:02:46 INFO - PROCESS | 2499 | 18:05:48 INFO - PROCESS | 2499 | Cycle 1(1): loaded http://localhost:61759/tests/dromaeo/cssquery-jquery.html (next: http://localhost:61759/tests/dromaeo/cssquery-mootools.html) 18:05:49 INFO - PROCESS | 2499 | RSS: Main: 359251968 18:05:49 INFO - PROCESS | 2499 | 18:08:51 INFO - PROCESS | 2499 | Cycle 1(1): loaded http://localhost:61759/tests/dromaeo/cssquery-mootools.html (next: http://localhost:61759/tests/dromaeo/cssquery-prototype.html) 18:08:52 INFO - PROCESS | 2499 | RSS: Main: 360898560 18:08:52 INFO - PROCESS | 2499 | 18:11:55 INFO - PROCESS | 2499 | Cycle 1(1): loaded http://localhost:61759/tests/dromaeo/cssquery-prototype.html (next: http://localhost:61759/tests/dromaeo/cssquery-yui.html) 18:11:55 INFO - PROCESS | 2499 | RSS: Main: 328462336 18:11:55 INFO - PROCESS | 2499 | 18:14:58 INFO - PROCESS | 2499 | Cycle 1(1): loaded http://localhost:61759/tests/dromaeo/cssquery-yui.html (next: http://localhost:61759/tests/dromaeo/cssquery-dojo.html) 18:14:58 INFO - PROCESS | 2499 | RSS: Main: 366522368 18:14:58 INFO - PROCESS | 2499 | 18:14:58 INFO - PROCESS | 2499 | __start_tp_report 18:14:58 INFO - PROCESS | 2499 | _x_x_mozilla_page_load 18:14:58 INFO - PROCESS | 2499 | _x_x_mozilla_page_load_details 18:14:58 INFO - PROCESS | 2499 | |i|pagename|runs| 18:14:58 INFO - PROCESS | 2499 | |0;dojo.html;3333.89998300051;3397.8640854365826;3401.0817079388567;3411.914702132448;3456.706179974702;5602.32772067352;5610.186522954169;5612.07400778871;5651.802186923458;5666.32004159501;5646.717664116798;5688.857778555538;5689.374168841424;5692.345380281258;5702.629329093606;5133.024725302199;5162.251473536335;5189.429162792099;5242.6330156889035;5273.947260527403;5276.736163191844;5282.366116066078;5300.602454815885;5305.867353316159;5326.92009619856;5181.3264275644115;5255.527002569768;5260.079486089919;5287.524122828944;5288.92066619001;5165.560927321201;5186.870328241786;5204.9479505204845;5216.921746173811;5254.133068043769;5029.522195391469;5072.188449848043;5076.82231121909;5126.846194614168;5138.486151384869;5471.452854714536;5526.668399896019;5555.749991250404;5563.916541251884;5565.053940830068;6119.265688117454;6142.201513803222;6153.077038444225;6159.692015399257;6173.660448675321;4956.776945037482;6152.5693201475915;6160.353162917919;6222.937770622327;6232.875342493125;5713.714314284352;5726.0838265877255;5734.885302293973;5738.713064346766;5782.421757824226;6326.746930122836;6329.936700633027;6333.96833015832;6337.366263373718;6344.080108384351;5972.372900845436;5984.670843103584;6055.333913269536;6063.393660633989;6065.090236464477;6201.472874805602;6219.689015549205;6220.502359811204;6235.906461403082;6255.280642726054;6085;6145.201123853871;6150.477209437158;6151.2618485781995;6179;4957.925631115536;6115.785947491817;6144.78493252734;6167.074938759132;6179.536534759911;4822.879428014257;4837.105135549935;4877.5366340197625;4879.26810978349;4928.088303663833;4385.451818522684;4390.187815254124;4405.669574781904;4407.735535867858;4417.249067658506;5549.639273447213;5610.102383618601;5633.943660563342;5643.887122257532;5677.063284558084;6228.408301211468;6237.937620623827;6251.3123556408755;6262.060690896311;6277.1525844011385;5640.520555752725;5654.886902261932;5655.802046928337;5663.292088488939;5667.4615911488345;5207.609429292818;5303.575713942875;5305.708186049805;5330.147176451826;5332;6215.875682486325;6289;6298.748050077946;6304.464120549713;6314.400131987454;6184.752609895554;6288.308286088435;6311.116443697794;6325.810225693236;6330.3669633036325;6270.341614130451;6307.526935479766;6326.272478664829;6328.303886572566;6340.017297318923;5949.167116603592;6118.357572454828;6127.387261273837;6136.969315153396;6154.938450615616;6117.218487018478;6144.7571636353305;6197.411245931809;6202.100695399232;6218.720157592828;4675.836345727883;5851.385604511465;5936.525077993664;5947.702614869326;5977.372375900468;5778.046622307273;5979.4618484336615;5997.790077347272;6000.909986350121;6005.279366476051;5759.049756790083;5776.537876969748;5810.477057064884;5819.79630712923;5839.766409343579;5843.474087332161;5938.346781853907;5974.074018527135;5984.970075149597;6005.3694362091355;4626;5531.502164805187;5540.833774986757;5557.138643510262;5563.109902415594 18:14:58 INFO - PROCESS | 2499 | |1;ext.html;491.9496874173639;496.97763600637967;514.7992283009627;517.9197224430213;524.62751446473;26189.73810261897;31019.9143029994;38027.85916422508;38460;38816.611833881725;32992.17519562006;41840;41846.953826154364;41870.95322616936;41917.161656766846;7493.363064139555;8440.915590844104;9582.856257156149;9583.089606487407;9605.135537801596;8590.312774978018;11553.537858485692;11773.587924422647;11775.7644847103;11776.116791240649;11442.141839362039;11505.022073123795;11529.942350288236;11543.076553875711;11651;8617.827643447095;9688.75778105546;11715.765684686301;11831.585894493737;11861.110416718737;11414.771704565863;11536.423178841109;11541.942290288578;11546.018588419995;11558.306501609846;39754;40592.594074059176;41177.79411102955;41182.764517064534;41282.761517154235;2729.6189615666967;3232.8060316381093;3270.0680306112645;3408.625051244361;3431.897043088711;28703.28241793951;28767.28081797951;29150.85424572885;29552.408951821057;29668;2578.845269283849;2587.650667159947;2595.9610605840926;2615.3069436599317;2630.053180854891;473403;475807;477856;478073;478084;35911.82044089763;63220.68389658114;63727.36272637214;63783;63796;36060;36095.27809443849;36350.90947271586;36352;36386.09034774152;15389.769153462707;15564.844351556567;15567;15586.9220653896;15625;10951.890481095246;12221.327826969602;12410.5656302029;13059;13331.60005199826;8190.8771368429525;8201.548914809742;8249.505029698117;8296.875546866802;8313.33493320545;3351.346487434927;3391.609964854024;3396.7452441066525;3408.5398471206568;3419.384510788082;1010.015235145727;1040.8627683783398;1053.1837825685154;1071.919606029551;1170.9707257318464;30119.096427107223;30209.546856797166;30576.08271751851;30603.69396306053;30876;958.9808203835884;1105.5688281570194;1128.7573171768108;1152.458344578047;1234.9073819463576;14381.352839122264;14640.414383424757;14670.119792812466;14913.850861491463;15004.474843380647;635824;640798;641277;644967;645124;49615;61435.6928215365;61679.69160154171;61719;61769;1345.132389608697;1486.1677460622086;1496.3490881466596;1497.2588568658584;1558.5246499817665;29126.417471650024;30325.848370758005;30340.39319213559;30704.539431908986;31076.689233108285;49729.25406118984;50027.99944001172;50257;50285.74857125691;50293.497065030344;360831.3916860798;360842;361059;362302.18848905584;362519;24066.39834004164;24581;26838.329041774112;27298.454030919667;27503.31241718973;41676;47052.52947470482;48588.75705621449;48675.269870951255;49102;36261.63738362583;37538.81230593829;37582.8120859394;37792.055198620255;38361.6573419929;37406.06484837901;37686;38644.61355386522;39211;40867;36427.27145457129;36769;37497;37984.15118866151;38135;25062.62406063874;26724.732752672226;32601.510977335834;32650.020499385057;33109.006729798144;20675;22280.554388911805;23688.104951604175;25238.621420678337;25980.35049123787 18:14:58 INFO - PROCESS | 2499 | |2;jquery.html;401.604419646648;403.32040511737716;404.3934098851722;408.3323765643174;416.922869269185;14890.510948905105;18560;19867.50331241718;20583.176672933063;20593.485162870937;13743.831774299197;15108.773368399456;18118.728219076726;18831.71752423715;20465.260452861523;5679.346875109363;5760.942390576082;5778.59549831512;5837.620554663955;5954.583179177459;4142.647874930634;4349.456317960255;4355.802154407532;5900.498457631106;5910.172575839364;3545.7340699447514;4339.132173565299;6215.471684906765;6487.513436492259;6507.739690412378;6209.161763161983;6456.741730330781;6464.353564643545;6464.45052170566;6473.643949582771;5639.182318563785;5662.122371032497;5664.0371136906415;5664.122061080539;5664.461876121762;24438.511229775304;24838.3855049423;25014.874925625438;26182.6072608911;26263.21210363692;1932.9710054349196;2039.1741344755249;2174.2716190076344;2376;2847.2027832207013;9610.375325603813;9742.123208911162;9793.167580755644;10068;10088.495575221283;1659.585103724069;1683.8568721658673;1823.4803081121815;1832.0839580209895;1851.833334999843;78711;96740;109144;110960.89039109668;113046;11504.654860354203;11597.072234221241;11702.180847340604;11726.765464690658;11727.413629318671;15106.54680359591;15153.469628562947;15174.468893588672;15217.31522081509;15281.46514871974;7529.134149572869;7806.336461400844;8244.917550824535;8458;8876.911230887737;8245.680691089517;8757.693480728143;8834.204921557091;8869.423487473294;8934.195922366887;4301.849435269751;4648.418947631546;4668.626509879269;4688.5545873142;4763.3807605011125;1722.477300143073;1912.9426117216506;1950.2589016173763;1995.2517805822818;2000.6998950157301;898.7753061734567;1002.7142264454595;1007.0885848307294;1026.4764969865423;1077.6928575355985;19412;19447.416577502696;20090.89954550218;20257.403473697363;20310.187592496135;952;961.8941916389192;967.7677357434305;995.2983146881431;1036.3937096798306;8975.506347150838;10594.629187978384;10598.947005265078;10667.359958402521;10675.199360048027;467443;608803;623761;624723;629728;121542.392288038;125179;126289.36855315848;126697.36651316869;127493;924.2882980105281;993.4833886379187;998.4358837256926;1014.852846337277;1016.9898301016896;17194.828051719323;17618.85477443962;17714.64570708553;17918.283268669627;17993.853031573148;49135.48949317666;49484.50515494799;49887.75056124842;50330.748346258035;50974.23538646849;73582.63208683922;73737.6313118431;74846.62576687081;74942.87585686328;76992;8222.506649601042;8236.094029656613;8278.627461764114;8357.164283571838;8373.748787536384;8645.481271123752;9314.022027686995;10566.41884696349;11949.402529873298;12765.638356904003;9704.835419749676;9769.511524423893;10577.576896924036;11256.943715281372;11267.718307042389;8398.748037558884;11127.888721112686;11146.052585529997;11173.608923687632;11379.601713939972;11391.829122563002;11473.254238474472;11671.35807530594;11671.59149429766;11800.17398782077;8351.624176912175;8362.665493380196;8457.703980360659;8485.40602157843;8680.088590697886;10506.264561480928;11221.990020898158;11447.313161210353;11474.885251147381;11543.422828858691 18:14:58 INFO - PROCESS | 2499 | |3;mootools.html;1361.7684993551095;1534.6240171158067;1546.8530489603488;1752.4567384110926;1895.6398284325976;7971.601419929009;8677.219050285474;8867.822643547126;8887.289016878642;8901.332400069989;8566.100559441247;8867.822643547126;8869.60086796094;8871.512066836322;8875.733727988154;2173.1307477009245;2305.6310990241604;2429.963550546743;2495.6131799571003;2499.387650025746;2525.5832787590025;2530.0132948150144;2534.1890595009604;2535.6703628528358;2537.3656585853537;2400.95439184973;2477.2196758021255;2480.1443501991876;2480.466699659573;2483.9379015524664;2175.5431359414465;2253.0537174386795;2432.4891772727747;2474.505098980202;2489.166129346671;2388.6297623868327;2472.8269021168526;2475.158446128325;2476.95046099077;2478.9008439662393;8689.56552172389;8779.03430622631;8781.912180878237;8785.692500762507;8822.86765698515;5242.397124330712;5256.948610277921;5472.20653005312;5483.533899618497;5487.725613719298;3439.94840077399;3537.6462353764673;3590.1922067534856;3618.945715814265;3626.365386057456;1072.0780129088978;1636.5744906324323;1656.4782093640465;1665.4753752567901;1686.7469879518048;11647.35939523326;11826.994705450046;11850.3482308473;11860.644180674508;11862.584809531625;4605;4612.192866248393;4682.227432473604;4725.40932383452;4742.051589682077;9349.39228950116;9371.859422108673;9371.906280937239;9374.765630859147;9392.90607093934;5391.649542779707;5400.459954004568;5400.486953739389;5404.567634589223;5410.783568657288;4445.777711114431;4569.383133276965;4876.4392094909535;5003.949960500348;5025.195968645073;3560.732945029133;4017.184128329181;4061.3095773718537;4128.979355103205;4170.562090980464;1357.3484727330936;1371.3897315694421;1371.8833899118488;1381.951631692886;1382.7165431086603;871.6121326009865;877.9517126558101;916.4134953629682;924.9825192288563;952.7427594549433;5421.647592906448;5589.720513974285;5736.196932429463;5832.825015249549;5872.383399742966;976.5312649928075;980.3333733061444;994.6071301835879;996.2976101848179;1017.6183931025865;3658.743887927873;3749.568799588082;3837.2901013312626;3840.1935593525673;3850.364689826203;10379.325343852626;10680.359178449162;10705.571777128827;10859.891401085888;10876.564937402416;10254.28220024606;10710.518026688818;10759.462026898624;10805.189610779224;10886.891131088587;6768.763093291711;8025.357971362277;8496.490210587384;8498.490090594583;8520.957395212985;5702.714864256854;5729.197912292366;5729.4843464088435;5757.798477053283;6023.759049637965;7701.576413297098;9037.864432033393;9460.526973651153;9565.139137477661;9877.753056173653;9339.953300233456;9387.29595280343;9487.098725621056;9494.955554889095;9662.565184566853;6740.102626672157;6745.190577130776;7055.082839230867;7415.3326200642205;7578.242175782598;7726.690932362642;7781;7791.259830316111;8278.544680042414;8317.584120793816;7253;7365.447591430771;7416.035915330973;7465.664045118091;7563.394928405604;7258.854822903407;7274.4907856449545;7351.264873512606;7391.521695660997;7402.518836275625;6815.93184068153;6940.27127151662;7015.2283248843605;7050.788476345718;7068.399186069241;4736.834210802605;4784.641151913551;4805.423349198119;4918.852434426973;4999.65002449825;4457.153140903218;4533.637309015205;4544.318352247189;4575.313702944585;4728.219843725884 18:14:58 INFO - PROCESS | 2499 | |4;prototype.html;325.1092007898358;327.9327737813747;332.9816860072697;335.33101462582147;338.1175132903124;6098;6101.237345331833;6235.7505699771955;6269.749210031605;6289.748410063592;6156.383536996781;6182.5981311214855;6182.690865456731;6276.964300890348;6279.591826531284;3732.6080761520057;4590.655700822444;4694.389729335182;4713.599344055742;4762.714237145765;4689.062187562484;4761.833335833247;4766.428028636551;4771.618270538349;4774.2122549779415;4766.690165139272;4770;4792.305115758213;4800.5679488846;4801.879953001168;4538.909221815562;4627.23650597651;4741.2651039089;4743.976280118612;4803.591694705954;4059.776712280823;4069.552349241581;4085.3871919212356;4090.141070375225;4090.4886889138857;5962;5979.581429299951;5995.640261584319;5996.970015149896;6007.789727359565;5454.754536045887;5512.118061110203;5537.861553461155;5555.194496797942;5567.137093750475;6055.545834062463;6341.478045269102;6793.694283757242;6793.762218322385;6796.422304104157;4463.33050042493;4543.727376357396;4594.081183763261;4598.747068911209;4622.8150873965005;5702.344230413514;6238.313785483593;6758.560693554903;7225.783226503161;7240.2035776064595;3839.6544311012144;4681.765911704401;4691.7184968901975;4692.741899195577;4736.928946065812;4730.952690473051;4743.857684269477;4788.904221915543;4797.472278049412;4798.49615790344;4368.694191406571;5507.862303442366;5518.475744804237;5531.004419204582;5536.916946245809;6600.174978127734;6854.34883686049;6860.485463590151;6873.278305777922;6896.2069362023;1584.881133914961;1602.4791942618695;1611.4923799003277;1612.1455628516906;1612.7419612861886;960.9663661771805;1013.5439052426437;1015.4161357219629;1033.328336581213;1039.8076355874186;6066.545009124334;6072.605280656743;6079.452849243589;6087.3303936566945;6103.176071230327;5696.401877802771;6890.31096890307;7123.109611298588;7125.394341480928;7146.213916469184;6279.623222606659;6291.307956124822;6298.212723409574;6301.8109456716375;6317.084022816758;3715.052661571336;3775.7734535927384;3776.981115094407;3777.7166712496673;3793.886183414502;7261.2012678605315;7475.588842613708;7501.849963000709;7551.660175292125;7597.202293759186;7447.776566703008;7450.143233528105;7455.179930207672;7463.925360746323;7494.175640679521;6184.659843708459;7514.098308202942;7521.586312752851;7552.8489430212185;7558.319751222306;5820.679862607428;7123.465740069412;7190.42476601881;7193.280671932765;7213.67538460781;6811.7275308989065;7043.964780176067;7109.431245500244;7111.790995530876;7183.281671832984;7499.775006749806;7534.849303014019;7588.962055189689;7599.392048635986;7614.238576142341;4539.182947069559;4602.4477062752685;4611.953880461287;4613.792379342871;4620.815167393266;5809.535237180931;7153.964230178815;7171.748988785367;7173.318534739192;7198.8920166198595;6557.606543607399;6831.385175334045;6856.965715171392;6884.173899132136;6893.276205998499;6777.932220677927;6805.8638827222185;6825.35159159879;6825.965870170618;6863.210730766026;5467.808626698046;6651.16860392451;6688.431483323972;6712.563683360566;6734.191896972395;6834.419074378535;6840.2475727668925;6842.794716158523;6854.691538880862;6865.794026179223;6066.545009124245;6066.817995460143;6149.292831324455;6182.505399567934;6278.372162783685 18:14:58 INFO - PROCESS | 2499 | |5;yui.html;669.9229588597311;728.0753443127231;730.2040775554644;739.5969196787751;744.1776836595564;14976.17631030293;15335.309911054;15341.769873451907;15376.846231537695;15392.230388480573;13938.860611393882;13958.651033724162;13974.650633734162;14000.159990400558;14000.579982600513;699.0632552379823;727.9126504819429;863.8424511155051;866.7096522664915;898.348697194532;867.4188293843109;886.8093359927615;888.4136469929848;888.8177923525694;901.6934242357597;955.5461155950937;956.8708224389723;957.2820384711466;957.4303289542711;966.9323147379686;754.1252147508891;773.5745340062971;775.2441369664589;777.5490215674895;967.3615413826876;586.7946218823421;701.8420855307565;704.5666914847383;704.5666914847383;713.7608901018165;14663;14736.336864841109;14738.926305368512;14794.260286985607;14813.851861481462;12238.326892020936;12308.824406833652;12433.191842530206;12496.562620308336;12530.248185108923;9938.801223975552;10072;10177.236707246986;10286.177105831515;10431;3500.422430298998;3500.5974312953826;3502.614712381636;3552.3605750964816;3717.5724791649036;79617;79892;80118;80213.59893200555;82214;9454.43273403598;9499.572519236652;9499.810003799885;9594.184494317922;9671.468069256118;21921;21927.342179734635;21936.793476358955;21952.89023554904;21969.890150549465;7176.748813791597;7318.975343451921;7324;7491.138519070268;7663.348615367756;4023.1350259694304;4026.2350153470748;4064.8577299794365;4082.1019375737883;4118.629323360852;3547.4146765783357;3620.1311685195365;3621.6016238213774;3623.0942264433893;3624.7100231981376;631.0565704272143;670.5339788846704;680.5882441123049;695.6591270277528;704.3695892176473;455.3397573518412;491.1919891778005;528.8122716435687;599.7211296746947;617.3579477343512;7329.900514922804;7605.505642133244;8039.1091797935815;8039.799005024921;8228.465149765247;523.4847995708833;568.7838621323871;588.3733823477983;608.0787606775739;611.8042226487481;3688.5573731152435;3853.2293541291856;3873.5545412277384;3931.6068393160454;3944.2111577684577;93014;95095;95655;95822;95927;59793.10310345348;76941;78361.82457263145;79896;80287.58418286934;67850;69837.65081174663;70899.2910070903;71051.2894871055;72191.2780872195;7570.129435115033;7689.115751688628;7803.765887023398;7859.449838511249;7904.20957904228;46115.538844611125;47229.291560625934;47794.76102619465;47810.76094619505;48136.5186348146;62153.135405937894;66598.66700666466;66919.99620005606;67216.327836721;67992.6600366995;9964.800703985735;10001.699949001542;10044.849327260243;10048.296619236584;10098.394096354243;10609.310394824312;10751.301165424222;10844.783104338027;10898.509567069343;11444.027257682857;8422.535762509626;9991.700248992542;10205.74485637865;11012.724681883017;11532.94233528827;11350.035247004098;11496.195266331277;11611.419429028347;11738.76522469563;11818.822717659414;10006.499025146286;10774.946125269324;10899.128069754559;11096;11548.769024619629;8730.738077857675;9335.859962100438;9557.856632150662;9557.904420955701;9878.901210987799;9363.580771697585;9534.046595340687;9593.952030239805;9619.5671194795;9625.278104142077 18:14:58 INFO - PROCESS | 2499 | __end_tp_report 18:14:58 INFO - PROCESS | 2499 | __start_cc_report 18:14:58 INFO - PROCESS | 2499 | _x_x_mozilla_cycle_collect,902 18:14:58 INFO - PROCESS | 2499 | __end_cc_report 18:14:58 INFO - PROCESS | 2499 | __startTimestamp1462583698744__endTimestamp 18:14:58 INFO - PROCESS | 2499 | 18:14:58 INFO - PROCESS | 2499 | ------- Summary: start ------- 18:14:58 INFO - PROCESS | 2499 | Number of tests: 6 18:14:58 INFO - PROCESS | 2499 | 18:14:58 INFO - PROCESS | 2499 | [#0] dojo.html Cycles:165 Average:5659.90 Median:5759.05 stddev:635.47 (11.0%) stddev-sans-first:610.66 18:14:58 INFO - PROCESS | 2499 | Values: 3333.9 3397.9 3401.1 3411.9 3456.7 5602.3 5610.2 5612.1 5651.8 5666.3 5646.7 5688.9 5689.4 5692.3 5702.6 5133.0 5162.3 5189.4 5242.6 5273.9 5276.7 5282.4 5300.6 5305.9 5326.9 5181.3 5255.5 5260.1 5287.5 5288.9 5165.6 5186.9 5204.9 5216.9 5254.1 5029.5 5072.2 5076.8 5126.8 5138.5 5471.5 5526.7 5555.7 5563.9 5565.1 6119.3 6142.2 6153.1 6159.7 6173.7 4956.8 6152.6 6160.4 6222.9 6232.9 5713.7 5726.1 5734.9 5738.7 5782.4 6326.7 6329.9 6334.0 6337.4 6344.1 5972.4 5984.7 6055.3 6063.4 6065.1 6201.5 6219.7 6220.5 6235.9 6255.3 6085.0 6145.2 6150.5 6151.3 6179.0 4957.9 6115.8 6144.8 6167.1 6179.5 4822.9 4837.1 4877.5 4879.3 4928.1 4385.5 4390.2 4405.7 4407.7 4417.2 5549.6 5610.1 5633.9 5643.9 5677.1 6228.4 6237.9 6251.3 6262.1 6277.2 5640.5 5654.9 5655.8 5663.3 5667.5 5207.6 5303.6 5305.7 5330.1 5332.0 6215.9 6289.0 6298.7 6304.5 6314.4 6184.8 6288.3 6311.1 6325.8 6330.4 6270.3 6307.5 6326.3 6328.3 6340.0 5949.2 6118.4 6127.4 6137.0 6154.9 6117.2 6144.8 6197.4 6202.1 6218.7 4675.8 5851.4 5936.5 5947.7 5977.4 5778.0 5979.5 5997.8 6000.9 6005.3 5759.0 5776.5 5810.5 5819.8 5839.8 5843.5 5938.3 5974.1 5985.0 6005.4 4626.0 5531.5 5540.8 5557.1 5563.1 18:14:58 INFO - PROCESS | 2499 | 18:14:58 INFO - PROCESS | 2499 | [#1] ext.html Cycles:180 Average:62423.47 Median:3408.58 stddev:135500.51 (3975.3%) stddev-sans-first:135800.84 18:14:58 INFO - PROCESS | 2499 | Values: 491.9 497.0 514.8 517.9 524.6 26189.7 31019.9 38027.9 38460.0 38816.6 32992.2 41840.0 41847.0 41871.0 41917.2 7493.4 8440.9 9582.9 9583.1 9605.1 8590.3 11553.5 11773.6 11775.8 11776.1 11442.1 11505.0 11529.9 11543.1 11651.0 8617.8 9688.8 11715.8 11831.6 11861.1 11414.8 11536.4 11541.9 11546.0 11558.3 39754.0 40592.6 41177.8 41182.8 41282.8 2729.6 3232.8 3270.1 3408.6 3431.9 28703.3 28767.3 29150.9 29552.4 29668.0 2578.8 2587.7 2596.0 2615.3 2630.1 473403.0 475807.0 477856.0 478073.0 478084.0 35911.8 63220.7 63727.4 63783.0 63796.0 36060.0 36095.3 36350.9 36352.0 36386.1 15389.8 15564.8 15567.0 15586.9 15625.0 10951.9 12221.3 12410.6 13059.0 13331.6 8190.9 8201.5 8249.5 8296.9 8313.3 3351.3 3391.6 3396.7 3408.5 3419.4 1010.0 1040.9 1053.2 1071.9 1171.0 30119.1 30209.5 30576.1 30603.7 30876.0 959.0 1105.6 1128.8 1152.5 1234.9 14381.4 14640.4 14670.1 14913.9 15004.5 635824.0 640798.0 641277.0 644967.0 645124.0 49615.0 61435.7 61679.7 61719.0 61769.0 1345.1 1486.2 1496.3 1497.3 1558.5 29126.4 30325.8 30340.4 30704.5 31076.7 49729.3 50028.0 50257.0 50285.7 50293.5 360831.4 360842.0 361059.0 362302.2 362519.0 24066.4 24581.0 26838.3 27298.5 27503.3 41676.0 47052.5 48588.8 48675.3 49102.0 36261.6 37538.8 37582.8 37792.1 38361.7 37406.1 37686.0 38644.6 39211.0 40867.0 36427.3 36769.0 37497.0 37984.2 38135.0 25062.6 26724.7 32601.5 32650.0 33109.0 20675.0 22280.6 23688.1 25238.6 25980.4 18:14:58 INFO - PROCESS | 2499 | 18:14:58 INFO - PROCESS | 2499 | [#2] jquery.html Cycles:180 Average:33873.09 Median:3196.47 stddev:98837.78 (3092.1%) stddev-sans-first:99083.10 18:14:58 INFO - PROCESS | 2499 | Values: 401.6 403.3 404.4 408.3 416.9 14890.5 18560.0 19867.5 20583.2 20593.5 13743.8 15108.8 18118.7 18831.7 20465.3 5679.3 5760.9 5778.6 5837.6 5954.6 4142.6 4349.5 4355.8 5900.5 5910.2 3545.7 4339.1 6215.5 6487.5 6507.7 6209.2 6456.7 6464.4 6464.5 6473.6 5639.2 5662.1 5664.0 5664.1 5664.5 24438.5 24838.4 25014.9 26182.6 26263.2 1933.0 2039.2 2174.3 2376.0 2847.2 9610.4 9742.1 9793.2 10068.0 10088.5 1659.6 1683.9 1823.5 1832.1 1851.8 78711.0 96740.0 109144.0 110960.9 113046.0 11504.7 11597.1 11702.2 11726.8 11727.4 15106.5 15153.5 15174.5 15217.3 15281.5 7529.1 7806.3 8244.9 8458.0 8876.9 8245.7 8757.7 8834.2 8869.4 8934.2 4301.8 4648.4 4668.6 4688.6 4763.4 1722.5 1912.9 1950.3 1995.3 2000.7 898.8 1002.7 1007.1 1026.5 1077.7 19412.0 19447.4 20090.9 20257.4 20310.2 952.0 961.9 967.8 995.3 1036.4 8975.5 10594.6 10598.9 10667.4 10675.2 467443.0 608803.0 623761.0 624723.0 629728.0 121542.4 125179.0 126289.4 126697.4 127493.0 924.3 993.5 998.4 1014.9 1017.0 17194.8 17618.9 17714.6 17918.3 17993.9 49135.5 49484.5 49887.8 50330.7 50974.2 73582.6 73737.6 74846.6 74942.9 76992.0 8222.5 8236.1 8278.6 8357.2 8373.7 8645.5 9314.0 10566.4 11949.4 12765.6 9704.8 9769.5 10577.6 11256.9 11267.7 8398.7 11127.9 11146.1 11173.6 11379.6 11391.8 11473.3 11671.4 11671.6 11800.2 8351.6 8362.7 8457.7 8485.4 8680.1 10506.3 11222.0 11447.3 11474.9 11543.4 18:14:58 INFO - PROCESS | 2499 | 18:14:58 INFO - PROCESS | 2499 | [#3] mootools.html Cycles:180 Average:5650.14 Median:5133.80 stddev:3092.62 (60.2%) stddev-sans-first:3084.50 18:14:58 INFO - PROCESS | 2499 | Values: 1361.8 1534.6 1546.9 1752.5 1895.6 7971.6 8677.2 8867.8 8887.3 8901.3 8566.1 8867.8 8869.6 8871.5 8875.7 2173.1 2305.6 2430.0 2495.6 2499.4 2525.6 2530.0 2534.2 2535.7 2537.4 2401.0 2477.2 2480.1 2480.5 2483.9 2175.5 2253.1 2432.5 2474.5 2489.2 2388.6 2472.8 2475.2 2477.0 2478.9 8689.6 8779.0 8781.9 8785.7 8822.9 5242.4 5256.9 5472.2 5483.5 5487.7 3439.9 3537.6 3590.2 3618.9 3626.4 1072.1 1636.6 1656.5 1665.5 1686.7 11647.4 11827.0 11850.3 11860.6 11862.6 4605.0 4612.2 4682.2 4725.4 4742.1 9349.4 9371.9 9371.9 9374.8 9392.9 5391.6 5400.5 5400.5 5404.6 5410.8 4445.8 4569.4 4876.4 5003.9 5025.2 3560.7 4017.2 4061.3 4129.0 4170.6 1357.3 1371.4 1371.9 1382.0 1382.7 871.6 878.0 916.4 925.0 952.7 5421.6 5589.7 5736.2 5832.8 5872.4 976.5 980.3 994.6 996.3 1017.6 3658.7 3749.6 3837.3 3840.2 3850.4 10379.3 10680.4 10705.6 10859.9 10876.6 10254.3 10710.5 10759.5 10805.2 10886.9 6768.8 8025.4 8496.5 8498.5 8521.0 5702.7 5729.2 5729.5 5757.8 6023.8 7701.6 9037.9 9460.5 9565.1 9877.8 9340.0 9387.3 9487.1 9495.0 9662.6 6740.1 6745.2 7055.1 7415.3 7578.2 7726.7 7781.0 7791.3 8278.5 8317.6 7253.0 7365.4 7416.0 7465.7 7563.4 7258.9 7274.5 7351.3 7391.5 7402.5 6815.9 6940.3 7015.2 7050.8 7068.4 4736.8 4784.6 4805.4 4918.9 4999.7 4457.2 4533.6 4544.3 4575.3 4728.2 18:14:58 INFO - PROCESS | 2499 | 18:14:58 INFO - PROCESS | 2499 | [#4] prototype.html Cycles:180 Average:5558.25 Median:6092.67 stddev:1764.29 (29.0%) stddev-sans-first:1724.97 18:14:58 INFO - PROCESS | 2499 | Values: 325.1 327.9 333.0 335.3 338.1 6098.0 6101.2 6235.8 6269.7 6289.7 6156.4 6182.6 6182.7 6277.0 6279.6 3732.6 4590.7 4694.4 4713.6 4762.7 4689.1 4761.8 4766.4 4771.6 4774.2 4766.7 4770.0 4792.3 4800.6 4801.9 4538.9 4627.2 4741.3 4744.0 4803.6 4059.8 4069.6 4085.4 4090.1 4090.5 5962.0 5979.6 5995.6 5997.0 6007.8 5454.8 5512.1 5537.9 5555.2 5567.1 6055.5 6341.5 6793.7 6793.8 6796.4 4463.3 4543.7 4594.1 4598.7 4622.8 5702.3 6238.3 6758.6 7225.8 7240.2 3839.7 4681.8 4691.7 4692.7 4736.9 4731.0 4743.9 4788.9 4797.5 4798.5 4368.7 5507.9 5518.5 5531.0 5536.9 6600.2 6854.3 6860.5 6873.3 6896.2 1584.9 1602.5 1611.5 1612.1 1612.7 961.0 1013.5 1015.4 1033.3 1039.8 6066.5 6072.6 6079.5 6087.3 6103.2 5696.4 6890.3 7123.1 7125.4 7146.2 6279.6 6291.3 6298.2 6301.8 6317.1 3715.1 3775.8 3777.0 3777.7 3793.9 7261.2 7475.6 7501.8 7551.7 7597.2 7447.8 7450.1 7455.2 7463.9 7494.2 6184.7 7514.1 7521.6 7552.8 7558.3 5820.7 7123.5 7190.4 7193.3 7213.7 6811.7 7044.0 7109.4 7111.8 7183.3 7499.8 7534.8 7589.0 7599.4 7614.2 4539.2 4602.4 4612.0 4613.8 4620.8 5809.5 7154.0 7171.7 7173.3 7198.9 6557.6 6831.4 6857.0 6884.2 6893.3 6777.9 6805.9 6825.4 6826.0 6863.2 5467.8 6651.2 6688.4 6712.6 6734.2 6834.4 6840.2 6842.8 6854.7 6865.8 6066.5 6066.8 6149.3 6182.5 6278.4 18:14:58 INFO - PROCESS | 2499 | 18:14:58 INFO - PROCESS | 2499 | [#5] yui.html Cycles:180 Average:18054.59 Median:33634.29 stddev:25637.36 (76.2%) stddev-sans-first:25676.05 18:14:58 INFO - PROCESS | 2499 | Values: 669.9 728.1 730.2 739.6 744.2 14976.2 15335.3 15341.8 15376.8 15392.2 13938.9 13958.7 13974.7 14000.2 14000.6 699.1 727.9 863.8 866.7 898.3 867.4 886.8 888.4 888.8 901.7 955.5 956.9 957.3 957.4 966.9 754.1 773.6 775.2 777.5 967.4 586.8 701.8 704.6 704.6 713.8 14663.0 14736.3 14738.9 14794.3 14813.9 12238.3 12308.8 12433.2 12496.6 12530.2 9938.8 10072.0 10177.2 10286.2 10431.0 3500.4 3500.6 3502.6 3552.4 3717.6 79617.0 79892.0 80118.0 80213.6 82214.0 9454.4 9499.6 9499.8 9594.2 9671.5 21921.0 21927.3 21936.8 21952.9 21969.9 7176.7 7319.0 7324.0 7491.1 7663.3 4023.1 4026.2 4064.9 4082.1 4118.6 3547.4 3620.1 3621.6 3623.1 3624.7 631.1 670.5 680.6 695.7 704.4 455.3 491.2 528.8 599.7 617.4 7329.9 7605.5 8039.1 8039.8 8228.5 523.5 568.8 588.4 608.1 611.8 3688.6 3853.2 3873.6 3931.6 3944.2 93014.0 95095.0 95655.0 95822.0 95927.0 59793.1 76941.0 78361.8 79896.0 80287.6 67850.0 69837.7 70899.3 71051.3 72191.3 7570.1 7689.1 7803.8 7859.4 7904.2 46115.5 47229.3 47794.8 47810.8 48136.5 62153.1 66598.7 66920.0 67216.3 67992.7 9964.8 10001.7 10044.8 10048.3 10098.4 10609.3 10751.3 10844.8 10898.5 11444.0 8422.5 9991.7 10205.7 11012.7 11532.9 11350.0 11496.2 11611.4 11738.8 11818.8 10006.5 10774.9 10899.1 11096.0 11548.8 8730.7 9335.9 9557.9 9557.9 9878.9 9363.6 9534.0 9594.0 9619.6 9625.3 18:14:58 INFO - PROCESS | 2499 | -------- Summary: end -------- 18:14:58 INFO - PROCESS | 2499 | 18:14:59 INFO - TEST-INFO | 2499: exit 0 18:14:59 INFO - TEST-OK | dromaeo_css | took 1115070ms 18:14:59 INFO - TEST-START | kraken 18:14:59 INFO - operating with platform_type : mac_ 18:14:59 INFO - Initialising browser for kraken test... 18:14:59 INFO - TEST-INFO | started process 2501 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/tmpjSabIS/profile http://localhost:61759/getInfo.html) 18:15:01 INFO - PROCESS | 2501 | __metrics Screen width/height:1600/1200 18:15:01 INFO - PROCESS | 2501 | colorDepth:24 18:15:01 INFO - PROCESS | 2501 | Browser inner width/height: 1024/689 18:15:01 INFO - PROCESS | 2501 | __metrics 18:15:01 INFO - PROCESS | 2501 | 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" 18:15:01 ERROR - PROCESS | 2501 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:15:02 INFO - TEST-INFO | 2501: exit 0 18:15:02 INFO - Browser initialized. 18:15:02 INFO - Running cycle 1/1 for kraken test... 18:15:02 INFO - TEST-INFO | started process 2503 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/T/tmpjSabIS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:15:14 INFO - PROCESS | 2503 | RSS: Main: 211124224 18:15:14 INFO - PROCESS | 2503 | 18:15:16 INFO - PROCESS | 2503 | Cycle 1(1): loaded http://localhost:61759/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:61759/tests/kraken/driver.html?testName=audio-beat-detection) 18:15:17 INFO - PROCESS | 2503 | RSS: Main: 271310848 18:15:17 INFO - PROCESS | 2503 | 18:15:20 INFO - PROCESS | 2503 | Cycle 1(1): loaded http://localhost:61759/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:61759/tests/kraken/driver.html?testName=audio-dft) 18:15:21 INFO - PROCESS | 2503 | RSS: Main: 285003776 18:15:21 INFO - PROCESS | 2503 | 18:15:24 INFO - PROCESS | 2503 | Cycle 1(1): loaded http://localhost:61759/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:61759/tests/kraken/driver.html?testName=audio-fft) 18:15:24 INFO - PROCESS | 2503 | RSS: Main: 375361536 18:15:24 INFO - PROCESS | 2503 | 18:15:26 INFO - PROCESS | 2503 | Cycle 1(1): loaded http://localhost:61759/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:61759/tests/kraken/driver.html?testName=audio-oscillator) 18:15:27 INFO - PROCESS | 2503 | RSS: Main: 277766144 18:15:27 INFO - PROCESS | 2503 | 18:15:29 INFO - PROCESS | 2503 | Cycle 1(1): loaded http://localhost:61759/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:61759/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:15:30 INFO - PROCESS | 2503 | RSS: Main: 278605824 18:15:30 INFO - PROCESS | 2503 | 18:15:32 INFO - PROCESS | 2503 | Cycle 1(1): loaded http://localhost:61759/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:61759/tests/kraken/driver.html?testName=imaging-darkroom) 18:15:33 INFO - PROCESS | 2503 | RSS: Main: 316444672 18:15:33 INFO - PROCESS | 2503 | 18:15:36 INFO - PROCESS | 2503 | Cycle 1(1): loaded http://localhost:61759/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:61759/tests/kraken/driver.html?testName=imaging-desaturate) 18:15:37 INFO - PROCESS | 2503 | RSS: Main: 318521344 18:15:37 INFO - PROCESS | 2503 | 18:15:39 INFO - PROCESS | 2503 | Cycle 1(1): loaded http://localhost:61759/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:61759/tests/kraken/driver.html?testName=json-parse-financial) 18:15:40 INFO - PROCESS | 2503 | RSS: Main: 316633088 18:15:40 INFO - PROCESS | 2503 | 18:15:42 INFO - PROCESS | 2503 | Cycle 1(1): loaded http://localhost:61759/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:61759/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:15:42 INFO - PROCESS | 2503 | RSS: Main: 308809728 18:15:42 INFO - PROCESS | 2503 | 18:15:44 INFO - PROCESS | 2503 | Cycle 1(1): loaded http://localhost:61759/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:61759/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:15:45 INFO - PROCESS | 2503 | RSS: Main: 314953728 18:15:45 INFO - PROCESS | 2503 | 18:15:47 INFO - PROCESS | 2503 | Cycle 1(1): loaded http://localhost:61759/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:61759/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:15:48 INFO - PROCESS | 2503 | RSS: Main: 318640128 18:15:48 INFO - PROCESS | 2503 | 18:15:50 INFO - PROCESS | 2503 | Cycle 1(1): loaded http://localhost:61759/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:61759/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:15:51 INFO - PROCESS | 2503 | RSS: Main: 323678208 18:15:51 INFO - PROCESS | 2503 | 18:15:54 INFO - PROCESS | 2503 | Cycle 1(1): loaded http://localhost:61759/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:61759/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:15:54 INFO - PROCESS | 2503 | RSS: Main: 321732608 18:15:54 INFO - PROCESS | 2503 | 18:15:56 INFO - PROCESS | 2503 | Cycle 1(1): loaded http://localhost:61759/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:61759/tests/kraken/driver.html?testName=ai-astar) 18:15:56 INFO - PROCESS | 2503 | RSS: Main: 385556480 18:15:56 INFO - PROCESS | 2503 | 18:15:56 INFO - PROCESS | 2503 | __start_tp_report 18:15:56 INFO - PROCESS | 2503 | _x_x_mozilla_page_load 18:15:56 INFO - PROCESS | 2503 | _x_x_mozilla_page_load_details 18:15:56 INFO - PROCESS | 2503 | |i|pagename|runs| 18:15:56 INFO - PROCESS | 2503 | |0;ai-astar;113;113;119;112;113;111;113;112;112;133 18:15:56 INFO - PROCESS | 2503 | |1;audio-beat-detection;153;120;158;146;120;183;176;115;169;121 18:15:56 INFO - PROCESS | 2503 | |2;audio-dft;181;186;185;183;185;183;185;186;186;184 18:15:56 INFO - PROCESS | 2503 | |3;audio-fft;71;77;76;152;55;56;57;61;139;56 18:15:56 INFO - PROCESS | 2503 | |4;audio-oscillator;89;87;144;76;81;87;125;79;77;124 18:15:56 INFO - PROCESS | 2503 | |5;imaging-gaussian-blur;91;86;89;87;89;86;90;87;89;86 18:15:56 INFO - PROCESS | 2503 | |6;imaging-darkroom;95;95;95;94;94;94;95;95;94;94 18:15:57 INFO - PROCESS | 2503 | |7;imaging-desaturate;85;84;85;86;84;85;86;84;83;85 18:15:57 INFO - PROCESS | 2503 | |8;json-parse-financial;60;62;63;63;62;65;65;85;59;58 18:15:57 INFO - PROCESS | 2503 | |9;json-stringify-tinderbox;64;65;74;79;75;72;68;68;69;69 18:15:57 INFO - PROCESS | 2503 | |10;stanford-crypto-aes;81;82;83;81;80;81;80;82;83;85 18:15:57 INFO - PROCESS | 2503 | |11;stanford-crypto-ccm;128;132;125;113;144;141;137;146;152;170 18:15:57 INFO - PROCESS | 2503 | |12;stanford-crypto-pbkdf2;167;166;167;168;168;167;164;167;167;169 18:15:57 INFO - PROCESS | 2503 | |13;stanford-crypto-sha256-iterative;67;68;71;91;95;96;69;65;66;66 18:15:57 INFO - PROCESS | 2503 | __end_tp_report 18:15:57 INFO - PROCESS | 2503 | __start_cc_report 18:15:57 INFO - PROCESS | 2503 | _x_x_mozilla_cycle_collect,2061 18:15:57 INFO - PROCESS | 2503 | __end_cc_report 18:15:57 INFO - PROCESS | 2503 | __startTimestamp1462583756992__endTimestamp 18:15:57 INFO - PROCESS | 2503 | 18:15:57 INFO - PROCESS | 2503 | ------- Summary: start ------- 18:15:57 INFO - PROCESS | 2503 | Number of tests: 14 18:15:57 INFO - PROCESS | 2503 | 18:15:57 INFO - PROCESS | 2503 | [#0] ai-astar Cycles:10 Average:115.10 Median:113.00 stddev:6.66 (5.9%) stddev-sans-first:7.02 18:15:57 INFO - PROCESS | 2503 | Values: 113.0 113.0 119.0 112.0 113.0 111.0 113.0 112.0 112.0 133.0 18:15:57 INFO - PROCESS | 2503 | 18:15:57 INFO - PROCESS | 2503 | [#1] audio-beat-detection Cycles:10 Average:146.10 Median:155.50 stddev:25.67 (16.5%) stddev-sans-first:27.10 18:15:57 INFO - PROCESS | 2503 | Values: 153.0 120.0 158.0 146.0 120.0 183.0 176.0 115.0 169.0 121.0 18:15:57 INFO - PROCESS | 2503 | 18:15:57 INFO - PROCESS | 2503 | [#2] audio-dft Cycles:10 Average:184.40 Median:185.00 stddev:1.65 (0.9%) stddev-sans-first:1.20 18:15:57 INFO - PROCESS | 2503 | Values: 181.0 186.0 185.0 183.0 185.0 183.0 185.0 186.0 186.0 184.0 18:15:57 INFO - PROCESS | 2503 | 18:15:57 INFO - PROCESS | 2503 | [#3] audio-fft Cycles:10 Average:80.00 Median:59.00 stddev:35.65 (60.4%) stddev-sans-first:37.66 18:15:57 INFO - PROCESS | 2503 | Values: 71.0 77.0 76.0 152.0 55.0 56.0 57.0 61.0 139.0 56.0 18:15:57 INFO - PROCESS | 2503 | 18:15:57 INFO - PROCESS | 2503 | [#4] audio-oscillator Cycles:10 Average:96.90 Median:80.00 stddev:24.51 (30.6%) stddev-sans-first:25.83 18:15:57 INFO - PROCESS | 2503 | Values: 89.0 87.0 144.0 76.0 81.0 87.0 125.0 79.0 77.0 124.0 18:15:57 INFO - PROCESS | 2503 | 18:15:57 INFO - PROCESS | 2503 | [#5] imaging-gaussian-blur Cycles:10 Average:88.00 Median:89.00 stddev:1.83 (2.1%) stddev-sans-first:1.58 18:15:57 INFO - PROCESS | 2503 | Values: 91.0 86.0 89.0 87.0 89.0 86.0 90.0 87.0 89.0 86.0 18:15:57 INFO - PROCESS | 2503 | 18:15:57 INFO - PROCESS | 2503 | [#6] imaging-darkroom Cycles:10 Average:94.50 Median:95.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 18:15:57 INFO - PROCESS | 2503 | Values: 95.0 95.0 95.0 94.0 94.0 94.0 95.0 95.0 94.0 94.0 18:15:57 INFO - PROCESS | 2503 | 18:15:57 INFO - PROCESS | 2503 | [#7] imaging-desaturate Cycles:10 Average:84.70 Median:85.00 stddev:0.95 (1.1%) stddev-sans-first:1.00 18:15:57 INFO - PROCESS | 2503 | Values: 85.0 84.0 85.0 86.0 84.0 85.0 86.0 84.0 83.0 85.0 18:15:57 INFO - PROCESS | 2503 | 18:15:57 INFO - PROCESS | 2503 | [#8] json-parse-financial Cycles:10 Average:64.20 Median:63.00 stddev:7.67 (12.2%) stddev-sans-first:7.98 18:15:57 INFO - PROCESS | 2503 | Values: 60.0 62.0 63.0 63.0 62.0 65.0 65.0 85.0 59.0 58.0 18:15:57 INFO - PROCESS | 2503 | 18:15:57 INFO - PROCESS | 2503 | [#9] json-stringify-tinderbox Cycles:10 Average:70.30 Median:70.50 stddev:4.67 (6.6%) stddev-sans-first:4.36 18:15:57 INFO - PROCESS | 2503 | Values: 64.0 65.0 74.0 79.0 75.0 72.0 68.0 68.0 69.0 69.0 18:15:57 INFO - PROCESS | 2503 | 18:15:57 INFO - PROCESS | 2503 | [#10] stanford-crypto-aes Cycles:10 Average:81.80 Median:82.00 stddev:1.55 (1.9%) stddev-sans-first:1.62 18:15:57 INFO - PROCESS | 2503 | Values: 81.0 82.0 83.0 81.0 80.0 81.0 80.0 82.0 83.0 85.0 18:15:57 INFO - PROCESS | 2503 | 18:15:57 INFO - PROCESS | 2503 | [#11] stanford-crypto-ccm Cycles:10 Average:138.80 Median:142.50 stddev:15.82 (11.1%) stddev-sans-first:16.29 18:15:57 INFO - PROCESS | 2503 | Values: 128.0 132.0 125.0 113.0 144.0 141.0 137.0 146.0 152.0 170.0 18:15:57 INFO - PROCESS | 2503 | 18:15:57 INFO - PROCESS | 2503 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.00 Median:167.00 stddev:1.33 (0.8%) stddev-sans-first:1.41 18:15:57 INFO - PROCESS | 2503 | Values: 167.0 166.0 167.0 168.0 168.0 167.0 164.0 167.0 167.0 169.0 18:15:57 INFO - PROCESS | 2503 | 18:15:57 INFO - PROCESS | 2503 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.40 Median:70.00 stddev:13.01 (18.6%) stddev-sans-first:13.44 18:15:57 INFO - PROCESS | 2503 | Values: 67.0 68.0 71.0 91.0 95.0 96.0 69.0 65.0 66.0 66.0 18:15:57 INFO - PROCESS | 2503 | -------- Summary: end -------- 18:15:57 INFO - PROCESS | 2503 | 18:15:57 INFO - TEST-INFO | 2503: exit 0 18:15:57 INFO - TEST-OK | kraken | took 58460ms 18:15:57 INFO - SUITE-END | took 1173s 18:15:58 INFO - Completed test suite (00:19:33) 18:15:58 INFO - javascript benchmark 18:15:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3333.89998300051, 3397.8640854365826, 3401.0817079388567, 3411.914702132448, 3456.706179974702, 5602.32772067352, 5610.186522954169, 5612.07400778871, 5651.802186923458, 5666.32004159501, 5646.717664116798, 5688.857778555538, 5689.374168841424, 5692.345380281258, 5702.629329093606, 5133.024725302199, 5162.251473536335, 5189.429162792099, 5242.6330156889035, 5273.947260527403, 5276.736163191844, 5282.366116066078, 5300.602454815885, 5305.867353316159, 5326.92009619856, 5181.3264275644115, 5255.527002569768, 5260.079486089919, 5287.524122828944, 5288.92066619001, 5165.560927321201, 5186.870328241786, 5204.9479505204845, 5216.921746173811, 5254.133068043769, 5029.522195391469, 5072.188449848043, 5076.82231121909, 5126.846194614168, 5138.486151384869, 5471.452854714536, 5526.668399896019, 5555.749991250404, 5563.916541251884, 5565.053940830068, 6119.265688117454, 6142.201513803222, 6153.077038444225, 6159.692015399257, 6173.660448675321, 4956.776945037482, 6152.5693201475915, 6160.353162917919, 6222.937770622327, 6232.875342493125, 5713.714314284352, 5726.0838265877255, 5734.885302293973, 5738.713064346766, 5782.421757824226, 6326.746930122836, 6329.936700633027, 6333.96833015832, 6337.366263373718, 6344.080108384351, 5972.372900845436, 5984.670843103584, 6055.333913269536, 6063.393660633989, 6065.090236464477, 6201.472874805602, 6219.689015549205, 6220.502359811204, 6235.906461403082, 6255.280642726054, 6085.0, 6145.201123853871, 6150.477209437158, 6151.2618485781995, 6179.0, 4957.925631115536, 6115.785947491817, 6144.78493252734, 6167.074938759132, 6179.536534759911, 4822.879428014257, 4837.105135549935, 4877.5366340197625, 4879.26810978349, 4928.088303663833, 4385.451818522684, 4390.187815254124, 4405.669574781904, 4407.735535867858, 4417.249067658506, 5549.639273447213, 5610.102383618601, 5633.943660563342, 5643.887122257532, 5677.063284558084, 6228.408301211468, 6237.937620623827, 6251.3123556408755, 6262.060690896311, 6277.1525844011385, 5640.520555752725, 5654.886902261932, 5655.802046928337, 5663.292088488939, 5667.4615911488345, 5207.609429292818, 5303.575713942875, 5305.708186049805, 5330.147176451826, 5332.0, 6215.875682486325, 6289.0, 6298.748050077946, 6304.464120549713, 6314.400131987454, 6184.752609895554, 6288.308286088435, 6311.116443697794, 6325.810225693236, 6330.3669633036325, 6270.341614130451, 6307.526935479766, 6326.272478664829, 6328.303886572566, 6340.017297318923, 5949.167116603592, 6118.357572454828, 6127.387261273837, 6136.969315153396, 6154.938450615616, 6117.218487018478, 6144.7571636353305, 6197.411245931809, 6202.100695399232, 6218.720157592828, 4675.836345727883, 5851.385604511465, 5936.525077993664, 5947.702614869326, 5977.372375900468, 5778.046622307273, 5979.4618484336615, 5997.790077347272, 6000.909986350121, 6005.279366476051, 5759.049756790083, 5776.537876969748, 5810.477057064884, 5819.79630712923, 5839.766409343579, 5843.474087332161, 5938.346781853907, 5974.074018527135, 5984.970075149597, 6005.3694362091355, 4626.0, 5531.502164805187, 5540.833774986757, 5557.138643510262, 5563.109902415594], "value": 5621.24541381515, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [491.9496874173639, 496.97763600637967, 514.7992283009627, 517.9197224430213, 524.62751446473, 26189.73810261897, 31019.9143029994, 38027.85916422508, 38460.0, 38816.611833881725, 32992.17519562006, 41840.0, 41846.953826154364, 41870.95322616936, 41917.161656766846, 7493.363064139555, 8440.915590844104, 9582.856257156149, 9583.089606487407, 9605.135537801596, 8590.312774978018, 11553.537858485692, 11773.587924422647, 11775.7644847103, 11776.116791240649, 11442.141839362039, 11505.022073123795, 11529.942350288236, 11543.076553875711, 11651.0, 8617.827643447095, 9688.75778105546, 11715.765684686301, 11831.585894493737, 11861.110416718737, 11414.771704565863, 11536.423178841109, 11541.942290288578, 11546.018588419995, 11558.306501609846, 39754.0, 40592.594074059176, 41177.79411102955, 41182.764517064534, 41282.761517154235, 2729.6189615666967, 3232.8060316381093, 3270.0680306112645, 3408.625051244361, 3431.897043088711, 28703.28241793951, 28767.28081797951, 29150.85424572885, 29552.408951821057, 29668.0, 2578.845269283849, 2587.650667159947, 2595.9610605840926, 2615.3069436599317, 2630.053180854891, 473403.0, 475807.0, 477856.0, 478073.0, 478084.0, 35911.82044089763, 63220.68389658114, 63727.36272637214, 63783.0, 63796.0, 36060.0, 36095.27809443849, 36350.90947271586, 36352.0, 36386.09034774152, 15389.769153462707, 15564.844351556567, 15567.0, 15586.9220653896, 15625.0, 10951.890481095246, 12221.327826969602, 12410.5656302029, 13059.0, 13331.60005199826, 8190.8771368429525, 8201.548914809742, 8249.505029698117, 8296.875546866802, 8313.33493320545, 3351.346487434927, 3391.609964854024, 3396.7452441066525, 3408.5398471206568, 3419.384510788082, 1010.015235145727, 1040.8627683783398, 1053.1837825685154, 1071.919606029551, 1170.9707257318464, 30119.096427107223, 30209.546856797166, 30576.08271751851, 30603.69396306053, 30876.0, 958.9808203835884, 1105.5688281570194, 1128.7573171768108, 1152.458344578047, 1234.9073819463576, 14381.352839122264, 14640.414383424757, 14670.119792812466, 14913.850861491463, 15004.474843380647, 635824.0, 640798.0, 641277.0, 644967.0, 645124.0, 49615.0, 61435.6928215365, 61679.69160154171, 61719.0, 61769.0, 1345.132389608697, 1486.1677460622086, 1496.3490881466596, 1497.2588568658584, 1558.5246499817665, 29126.417471650024, 30325.848370758005, 30340.39319213559, 30704.539431908986, 31076.689233108285, 49729.25406118984, 50027.99944001172, 50257.0, 50285.74857125691, 50293.497065030344, 360831.3916860798, 360842.0, 361059.0, 362302.18848905584, 362519.0, 24066.39834004164, 24581.0, 26838.329041774112, 27298.454030919667, 27503.31241718973, 41676.0, 47052.52947470482, 48588.75705621449, 48675.269870951255, 49102.0, 36261.63738362583, 37538.81230593829, 37582.8120859394, 37792.055198620255, 38361.6573419929, 37406.06484837901, 37686.0, 38644.61355386522, 39211.0, 40867.0, 36427.27145457129, 36769.0, 37497.0, 37984.15118866151, 38135.0, 25062.62406063874, 26724.732752672226, 32601.510977335834, 32650.020499385057, 33109.006729798144, 20675.0, 22280.554388911805, 23688.104951604175, 25238.621420678337, 25980.35049123787], "value": 18506.118093028403, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [401.604419646648, 403.32040511737716, 404.3934098851722, 408.3323765643174, 416.922869269185, 14890.510948905105, 18560.0, 19867.50331241718, 20583.176672933063, 20593.485162870937, 13743.831774299197, 15108.773368399456, 18118.728219076726, 18831.71752423715, 20465.260452861523, 5679.346875109363, 5760.942390576082, 5778.59549831512, 5837.620554663955, 5954.583179177459, 4142.647874930634, 4349.456317960255, 4355.802154407532, 5900.498457631106, 5910.172575839364, 3545.7340699447514, 4339.132173565299, 6215.471684906765, 6487.513436492259, 6507.739690412378, 6209.161763161983, 6456.741730330781, 6464.353564643545, 6464.45052170566, 6473.643949582771, 5639.182318563785, 5662.122371032497, 5664.0371136906415, 5664.122061080539, 5664.461876121762, 24438.511229775304, 24838.3855049423, 25014.874925625438, 26182.6072608911, 26263.21210363692, 1932.9710054349196, 2039.1741344755249, 2174.2716190076344, 2376.0, 2847.2027832207013, 9610.375325603813, 9742.123208911162, 9793.167580755644, 10068.0, 10088.495575221283, 1659.585103724069, 1683.8568721658673, 1823.4803081121815, 1832.0839580209895, 1851.833334999843, 78711.0, 96740.0, 109144.0, 110960.89039109668, 113046.0, 11504.654860354203, 11597.072234221241, 11702.180847340604, 11726.765464690658, 11727.413629318671, 15106.54680359591, 15153.469628562947, 15174.468893588672, 15217.31522081509, 15281.46514871974, 7529.134149572869, 7806.336461400844, 8244.917550824535, 8458.0, 8876.911230887737, 8245.680691089517, 8757.693480728143, 8834.204921557091, 8869.423487473294, 8934.195922366887, 4301.849435269751, 4648.418947631546, 4668.626509879269, 4688.5545873142, 4763.3807605011125, 1722.477300143073, 1912.9426117216506, 1950.2589016173763, 1995.2517805822818, 2000.6998950157301, 898.7753061734567, 1002.7142264454595, 1007.0885848307294, 1026.4764969865423, 1077.6928575355985, 19412.0, 19447.416577502696, 20090.89954550218, 20257.403473697363, 20310.187592496135, 952.0, 961.8941916389192, 967.7677357434305, 995.2983146881431, 1036.3937096798306, 8975.506347150838, 10594.629187978384, 10598.947005265078, 10667.359958402521, 10675.199360048027, 467443.0, 608803.0, 623761.0, 624723.0, 629728.0, 121542.392288038, 125179.0, 126289.36855315848, 126697.36651316869, 127493.0, 924.2882980105281, 993.4833886379187, 998.4358837256926, 1014.852846337277, 1016.9898301016896, 17194.828051719323, 17618.85477443962, 17714.64570708553, 17918.283268669627, 17993.853031573148, 49135.48949317666, 49484.50515494799, 49887.75056124842, 50330.748346258035, 50974.23538646849, 73582.63208683922, 73737.6313118431, 74846.62576687081, 74942.87585686328, 76992.0, 8222.506649601042, 8236.094029656613, 8278.627461764114, 8357.164283571838, 8373.748787536384, 8645.481271123752, 9314.022027686995, 10566.41884696349, 11949.402529873298, 12765.638356904003, 9704.835419749676, 9769.511524423893, 10577.576896924036, 11256.943715281372, 11267.718307042389, 8398.748037558884, 11127.888721112686, 11146.052585529997, 11173.608923687632, 11379.601713939972, 11391.829122563002, 11473.254238474472, 11671.35807530594, 11671.59149429766, 11800.17398782077, 8351.624176912175, 8362.665493380196, 8457.703980360659, 8485.40602157843, 8680.088590697886, 10506.264561480928, 11221.990020898158, 11447.313161210353, 11474.885251147381, 11543.422828858691], "value": 9358.320363207537, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1361.7684993551095, 1534.6240171158067, 1546.8530489603488, 1752.4567384110926, 1895.6398284325976, 7971.601419929009, 8677.219050285474, 8867.822643547126, 8887.289016878642, 8901.332400069989, 8566.100559441247, 8867.822643547126, 8869.60086796094, 8871.512066836322, 8875.733727988154, 2173.1307477009245, 2305.6310990241604, 2429.963550546743, 2495.6131799571003, 2499.387650025746, 2525.5832787590025, 2530.0132948150144, 2534.1890595009604, 2535.6703628528358, 2537.3656585853537, 2400.95439184973, 2477.2196758021255, 2480.1443501991876, 2480.466699659573, 2483.9379015524664, 2175.5431359414465, 2253.0537174386795, 2432.4891772727747, 2474.505098980202, 2489.166129346671, 2388.6297623868327, 2472.8269021168526, 2475.158446128325, 2476.95046099077, 2478.9008439662393, 8689.56552172389, 8779.03430622631, 8781.912180878237, 8785.692500762507, 8822.86765698515, 5242.397124330712, 5256.948610277921, 5472.20653005312, 5483.533899618497, 5487.725613719298, 3439.94840077399, 3537.6462353764673, 3590.1922067534856, 3618.945715814265, 3626.365386057456, 1072.0780129088978, 1636.5744906324323, 1656.4782093640465, 1665.4753752567901, 1686.7469879518048, 11647.35939523326, 11826.994705450046, 11850.3482308473, 11860.644180674508, 11862.584809531625, 4605.0, 4612.192866248393, 4682.227432473604, 4725.40932383452, 4742.051589682077, 9349.39228950116, 9371.859422108673, 9371.906280937239, 9374.765630859147, 9392.90607093934, 5391.649542779707, 5400.459954004568, 5400.486953739389, 5404.567634589223, 5410.783568657288, 4445.777711114431, 4569.383133276965, 4876.4392094909535, 5003.949960500348, 5025.195968645073, 3560.732945029133, 4017.184128329181, 4061.3095773718537, 4128.979355103205, 4170.562090980464, 1357.3484727330936, 1371.3897315694421, 1371.8833899118488, 1381.951631692886, 1382.7165431086603, 871.6121326009865, 877.9517126558101, 916.4134953629682, 924.9825192288563, 952.7427594549433, 5421.647592906448, 5589.720513974285, 5736.196932429463, 5832.825015249549, 5872.383399742966, 976.5312649928075, 980.3333733061444, 994.6071301835879, 996.2976101848179, 1017.6183931025865, 3658.743887927873, 3749.568799588082, 3837.2901013312626, 3840.1935593525673, 3850.364689826203, 10379.325343852626, 10680.359178449162, 10705.571777128827, 10859.891401085888, 10876.564937402416, 10254.28220024606, 10710.518026688818, 10759.462026898624, 10805.189610779224, 10886.891131088587, 6768.763093291711, 8025.357971362277, 8496.490210587384, 8498.490090594583, 8520.957395212985, 5702.714864256854, 5729.197912292366, 5729.4843464088435, 5757.798477053283, 6023.759049637965, 7701.576413297098, 9037.864432033393, 9460.526973651153, 9565.139137477661, 9877.753056173653, 9339.953300233456, 9387.29595280343, 9487.098725621056, 9494.955554889095, 9662.565184566853, 6740.102626672157, 6745.190577130776, 7055.082839230867, 7415.3326200642205, 7578.242175782598, 7726.690932362642, 7781.0, 7791.259830316111, 8278.544680042414, 8317.584120793816, 7253.0, 7365.447591430771, 7416.035915330973, 7465.664045118091, 7563.394928405604, 7258.854822903407, 7274.4907856449545, 7351.264873512606, 7391.521695660997, 7402.518836275625, 6815.93184068153, 6940.27127151662, 7015.2283248843605, 7050.788476345718, 7068.399186069241, 4736.834210802605, 4784.641151913551, 4805.423349198119, 4918.852434426973, 4999.65002449825, 4457.153140903218, 4533.637309015205, 4544.318352247189, 4575.313702944585, 4728.219843725884], "value": 4623.682890549559, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [325.1092007898358, 327.9327737813747, 332.9816860072697, 335.33101462582147, 338.1175132903124, 6098.0, 6101.237345331833, 6235.7505699771955, 6269.749210031605, 6289.748410063592, 6156.383536996781, 6182.5981311214855, 6182.690865456731, 6276.964300890348, 6279.591826531284, 3732.6080761520057, 4590.655700822444, 4694.389729335182, 4713.599344055742, 4762.714237145765, 4689.062187562484, 4761.833335833247, 4766.428028636551, 4771.618270538349, 4774.2122549779415, 4766.690165139272, 4770.0, 4792.305115758213, 4800.5679488846, 4801.879953001168, 4538.909221815562, 4627.23650597651, 4741.2651039089, 4743.976280118612, 4803.591694705954, 4059.776712280823, 4069.552349241581, 4085.3871919212356, 4090.141070375225, 4090.4886889138857, 5962.0, 5979.581429299951, 5995.640261584319, 5996.970015149896, 6007.789727359565, 5454.754536045887, 5512.118061110203, 5537.861553461155, 5555.194496797942, 5567.137093750475, 6055.545834062463, 6341.478045269102, 6793.694283757242, 6793.762218322385, 6796.422304104157, 4463.33050042493, 4543.727376357396, 4594.081183763261, 4598.747068911209, 4622.8150873965005, 5702.344230413514, 6238.313785483593, 6758.560693554903, 7225.783226503161, 7240.2035776064595, 3839.6544311012144, 4681.765911704401, 4691.7184968901975, 4692.741899195577, 4736.928946065812, 4730.952690473051, 4743.857684269477, 4788.904221915543, 4797.472278049412, 4798.49615790344, 4368.694191406571, 5507.862303442366, 5518.475744804237, 5531.004419204582, 5536.916946245809, 6600.174978127734, 6854.34883686049, 6860.485463590151, 6873.278305777922, 6896.2069362023, 1584.881133914961, 1602.4791942618695, 1611.4923799003277, 1612.1455628516906, 1612.7419612861886, 960.9663661771805, 1013.5439052426437, 1015.4161357219629, 1033.328336581213, 1039.8076355874186, 6066.545009124334, 6072.605280656743, 6079.452849243589, 6087.3303936566945, 6103.176071230327, 5696.401877802771, 6890.31096890307, 7123.109611298588, 7125.394341480928, 7146.213916469184, 6279.623222606659, 6291.307956124822, 6298.212723409574, 6301.8109456716375, 6317.084022816758, 3715.052661571336, 3775.7734535927384, 3776.981115094407, 3777.7166712496673, 3793.886183414502, 7261.2012678605315, 7475.588842613708, 7501.849963000709, 7551.660175292125, 7597.202293759186, 7447.776566703008, 7450.143233528105, 7455.179930207672, 7463.925360746323, 7494.175640679521, 6184.659843708459, 7514.098308202942, 7521.586312752851, 7552.8489430212185, 7558.319751222306, 5820.679862607428, 7123.465740069412, 7190.42476601881, 7193.280671932765, 7213.67538460781, 6811.7275308989065, 7043.964780176067, 7109.431245500244, 7111.790995530876, 7183.281671832984, 7499.775006749806, 7534.849303014019, 7588.962055189689, 7599.392048635986, 7614.238576142341, 4539.182947069559, 4602.4477062752685, 4611.953880461287, 4613.792379342871, 4620.815167393266, 5809.535237180931, 7153.964230178815, 7171.748988785367, 7173.318534739192, 7198.8920166198595, 6557.606543607399, 6831.385175334045, 6856.965715171392, 6884.173899132136, 6893.276205998499, 6777.932220677927, 6805.8638827222185, 6825.35159159879, 6825.965870170618, 6863.210730766026, 5467.808626698046, 6651.16860392451, 6688.431483323972, 6712.563683360566, 6734.191896972395, 6834.419074378535, 6840.2475727668925, 6842.794716158523, 6854.691538880862, 6865.794026179223, 6066.545009124245, 6066.817995460143, 6149.292831324455, 6182.505399567934, 6278.372162783685], "value": 4976.207937355107, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [669.9229588597311, 728.0753443127231, 730.2040775554644, 739.5969196787751, 744.1776836595564, 14976.17631030293, 15335.309911054, 15341.769873451907, 15376.846231537695, 15392.230388480573, 13938.860611393882, 13958.651033724162, 13974.650633734162, 14000.159990400558, 14000.579982600513, 699.0632552379823, 727.9126504819429, 863.8424511155051, 866.7096522664915, 898.348697194532, 867.4188293843109, 886.8093359927615, 888.4136469929848, 888.8177923525694, 901.6934242357597, 955.5461155950937, 956.8708224389723, 957.2820384711466, 957.4303289542711, 966.9323147379686, 754.1252147508891, 773.5745340062971, 775.2441369664589, 777.5490215674895, 967.3615413826876, 586.7946218823421, 701.8420855307565, 704.5666914847383, 704.5666914847383, 713.7608901018165, 14663.0, 14736.336864841109, 14738.926305368512, 14794.260286985607, 14813.851861481462, 12238.326892020936, 12308.824406833652, 12433.191842530206, 12496.562620308336, 12530.248185108923, 9938.801223975552, 10072.0, 10177.236707246986, 10286.177105831515, 10431.0, 3500.422430298998, 3500.5974312953826, 3502.614712381636, 3552.3605750964816, 3717.5724791649036, 79617.0, 79892.0, 80118.0, 80213.59893200555, 82214.0, 9454.43273403598, 9499.572519236652, 9499.810003799885, 9594.184494317922, 9671.468069256118, 21921.0, 21927.342179734635, 21936.793476358955, 21952.89023554904, 21969.890150549465, 7176.748813791597, 7318.975343451921, 7324.0, 7491.138519070268, 7663.348615367756, 4023.1350259694304, 4026.2350153470748, 4064.8577299794365, 4082.1019375737883, 4118.629323360852, 3547.4146765783357, 3620.1311685195365, 3621.6016238213774, 3623.0942264433893, 3624.7100231981376, 631.0565704272143, 670.5339788846704, 680.5882441123049, 695.6591270277528, 704.3695892176473, 455.3397573518412, 491.1919891778005, 528.8122716435687, 599.7211296746947, 617.3579477343512, 7329.900514922804, 7605.505642133244, 8039.1091797935815, 8039.799005024921, 8228.465149765247, 523.4847995708833, 568.7838621323871, 588.3733823477983, 608.0787606775739, 611.8042226487481, 3688.5573731152435, 3853.2293541291856, 3873.5545412277384, 3931.6068393160454, 3944.2111577684577, 93014.0, 95095.0, 95655.0, 95822.0, 95927.0, 59793.10310345348, 76941.0, 78361.82457263145, 79896.0, 80287.58418286934, 67850.0, 69837.65081174663, 70899.2910070903, 71051.2894871055, 72191.2780872195, 7570.129435115033, 7689.115751688628, 7803.765887023398, 7859.449838511249, 7904.20957904228, 46115.538844611125, 47229.291560625934, 47794.76102619465, 47810.76094619505, 48136.5186348146, 62153.135405937894, 66598.66700666466, 66919.99620005606, 67216.327836721, 67992.6600366995, 9964.800703985735, 10001.699949001542, 10044.849327260243, 10048.296619236584, 10098.394096354243, 10609.310394824312, 10751.301165424222, 10844.783104338027, 10898.509567069343, 11444.027257682857, 8422.535762509626, 9991.700248992542, 10205.74485637865, 11012.724681883017, 11532.94233528827, 11350.035247004098, 11496.195266331277, 11611.419429028347, 11738.76522469563, 11818.822717659414, 10006.499025146286, 10774.946125269324, 10899.128069754559, 11096.0, 11548.769024619629, 8730.738077857675, 9335.859962100438, 9557.856632150662, 9557.904420955701, 9878.901210987799, 9363.580771697585, 9534.046595340687, 9593.952030239805, 9619.5671194795, 9625.278104142077], "value": 6749.087747142735, "unit": "score"}], "name": "dromaeo_css", "value": 7298.812051032698, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 119.0, 112.0, 113.0, 111.0, 113.0, 112.0, 112.0, 133.0], "value": 115.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 120.0, 158.0, 146.0, 120.0, 183.0, 176.0, 115.0, 169.0, 121.0], "value": 146.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 186.0, 185.0, 183.0, 185.0, 183.0, 185.0, 186.0, 186.0, 184.0], "value": 184.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 77.0, 76.0, 152.0, 55.0, 56.0, 57.0, 61.0, 139.0, 56.0], "value": 80.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 144.0, 76.0, 81.0, 87.0, 125.0, 79.0, 77.0, 124.0], "value": 96.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 86.0, 89.0, 87.0, 89.0, 86.0, 90.0, 87.0, 89.0, 86.0], "value": 88.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 94.0, 94.0, 94.0, 95.0, 95.0, 94.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 85.0, 86.0, 84.0, 85.0, 86.0, 84.0, 83.0, 85.0], "value": 84.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 62.0, 63.0, 63.0, 62.0, 65.0, 65.0, 85.0, 59.0, 58.0], "value": 64.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 74.0, 79.0, 75.0, 72.0, 68.0, 68.0, 69.0, 69.0], "value": 70.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 83.0, 81.0, 80.0, 81.0, 80.0, 82.0, 83.0, 85.0], "value": 81.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 132.0, 125.0, 113.0, 144.0, 141.0, 137.0, 146.0, 152.0, 170.0], "value": 138.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 166.0, 167.0, 168.0, 168.0, 167.0, 164.0, 167.0, 167.0, 169.0], "value": 167.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 71.0, 91.0, 95.0, 96.0, 69.0, 65.0, 66.0, 66.0], "value": 75.4, "unit": "score"}], "name": "kraken", "value": 1487.2, "alertThreshold": 2.0}]} 18:15:58 INFO - Return code: 0 18:15:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:15:58 ERROR - # TBPL SUCCESS # 18:15:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:15:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:15:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:15:58 INFO - 2016-05-06 18:15:58.280 screenresolution[2507:31629] starting screenresolution argv=screenresolution get 18:15:58 INFO - 2016-05-06 18:15:58.283 screenresolution[2507:31629] Display 0: 1600x1200x32@60 18:15:58 INFO - 2016-05-06 18:15:58.289 screenresolution[2508:31632] starting screenresolution argv=screenresolution list 18:15:58 INFO - Available Modes on Display 0 18:15:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:15:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:15:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:15:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:15:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:15:58 INFO - Intel Iris: 18:15:58 INFO - Chipset Model: Intel Iris 18:15:58 INFO - Type: GPU 18:15:58 INFO - Bus: Built-In 18:15:58 INFO - VRAM (Dynamic, Max): 1536 MB 18:15:58 INFO - Vendor: Intel (0x8086) 18:15:58 INFO - Device ID: 0x0a2e 18:15:58 INFO - Revision ID: 0x0009 18:15:58 INFO - Displays: 18:15:58 INFO - Display: 18:15:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:15:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:15:58 INFO - Display Serial Number: 1600x1200 60 18:15:58 INFO - Main Display: Yes 18:15:58 INFO - Mirror: Off 18:15:58 INFO - Online: Yes 18:15:58 INFO - Rotation: Supported 18:15:58 INFO - Return code: 0 18:15:58 INFO - Running post-action listener: _resource_record_post_action 18:15:58 INFO - Running post-run listener: _resource_record_post_run 18:15:59 INFO - Total resource usage - Wall time: 1207s; CPU: 52.0%; Read bytes: 45056; Write bytes: 651570688; Read time: 1; Write time: 2014 18:15:59 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 352862720; Write bytes: 347395584; Read time: 27538; Write time: 635 18:15:59 INFO - run-tests - Wall time: 1176s; CPU: 52.0%; Read bytes: 45056; Write bytes: 294434816; Read time: 1; Write time: 1345 18:15:59 INFO - Running post-run listener: _upload_blobber_files 18:15:59 INFO - Blob upload gear active. 18:15:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:15:59 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 18:15:59 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'] 18:15:59 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 18:16:00 INFO - (blobuploader) - INFO - Open directory for files ... 18:16:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:16:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:16:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:16:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:16:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:16:00 INFO - (blobuploader) - INFO - Done attempting. 18:16:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:16:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:16:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:16:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:16:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:16:01 INFO - (blobuploader) - INFO - Done attempting. 18:16:01 INFO - (blobuploader) - INFO - Iteration through files over. 18:16:01 INFO - Return code: 0 18:16:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:16:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:16:01 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b36505e019cb814c9975e5b99fda702995344abea5506173bea92bfe99aac02d74bd05d3d80c7aaab6b22eb13f152b99f0b70457b10149172db21afeef006e19", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4afb6ea007f698c0fc51c4906bac83e0c31c4723c9df2f2d83125c258fbee233f648ba54ed6ba7ae117b5b489543c9836c652fb1d488a3c276a27004bb7f760b"} 18:16:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:16:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:16:01 INFO - Contents: 18:16:01 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b36505e019cb814c9975e5b99fda702995344abea5506173bea92bfe99aac02d74bd05d3d80c7aaab6b22eb13f152b99f0b70457b10149172db21afeef006e19", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4afb6ea007f698c0fc51c4906bac83e0c31c4723c9df2f2d83125c258fbee233f648ba54ed6ba7ae117b5b489543c9836c652fb1d488a3c276a27004bb7f760b"} 18:16:01 INFO - Running post-run listener: copy_logs_to_upload_dir 18:16:01 INFO - Copying logs to upload dir... 18:16:01 INFO - mkdir: /builds/slave/test/build/upload/logs 18:16:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.135336 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-05-06 18:16:01.824240) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 18:16:01.827663) ========= 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.MPS3W5BrcD/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.F4I4fGSkig/Listeners TMPDIR=/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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/b36505e019cb814c9975e5b99fda702995344abea5506173bea92bfe99aac02d74bd05d3d80c7aaab6b22eb13f152b99f0b70457b10149172db21afeef006e19", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4afb6ea007f698c0fc51c4906bac83e0c31c4723c9df2f2d83125c258fbee233f648ba54ed6ba7ae117b5b489543c9836c652fb1d488a3c276a27004bb7f760b"} build_url:https://queue.taskcluster.net/v1/task/YHZWK5nvTKuinnqUdxFopg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011111 build_url: 'https://queue.taskcluster.net/v1/task/YHZWK5nvTKuinnqUdxFopg/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/b36505e019cb814c9975e5b99fda702995344abea5506173bea92bfe99aac02d74bd05d3d80c7aaab6b22eb13f152b99f0b70457b10149172db21afeef006e19", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4afb6ea007f698c0fc51c4906bac83e0c31c4723c9df2f2d83125c258fbee233f648ba54ed6ba7ae117b5b489543c9836c652fb1d488a3c276a27004bb7f760b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 18:16:01.858317) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 18:16:01.858637) ========= 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.MPS3W5BrcD/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.F4I4fGSkig/Listeners TMPDIR=/var/folders/wh/xl0_dj0x6qd32b8d3tzy392w00000w/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.005241 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 18:16:01.907122) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 18:16:01.907455) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 18:16:01.907750) ========= ========= Total master_lag: 0.16 =========