builder: larch_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0178 starttime: 1464725768.75 results: success (0) buildid: 20160531111747 builduid: b59ecb76b9974a20abb331f19affea40 revision: ea5c88b997faa8be5ef6a936304a908fd0292894 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-31 13:16:08.752169) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-31 13:16:08.752698) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-31 13:16:08.753182) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-31 13:16:09.014109) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 13:16:09.014439) ========= 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.1zJO8tpGU4/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.4opkTg6n29/Listeners TMPDIR=/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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-31 13:16:09-- 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% 10.4M=0.001s 2016-05-31 13:16:09 (10.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.142373 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 13:16:09.226725) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 13:16:09.227098) ========= 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.1zJO8tpGU4/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.4opkTg6n29/Listeners TMPDIR=/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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.027984 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 13:16:09.296267) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-31 13:16:09.296647) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev ea5c88b997faa8be5ef6a936304a908fd0292894 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev ea5c88b997faa8be5ef6a936304a908fd0292894 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.1zJO8tpGU4/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.4opkTg6n29/Listeners TMPDIR=/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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-31 13:16:09,382 truncating revision to first 12 chars 2016-05-31 13:16:09,382 Setting DEBUG logging. 2016-05-31 13:16:09,382 attempt 1/10 2016-05-31 13:16:09,382 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/ea5c88b997fa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-31 13:16:10,160 unpacking tar archive at: larch-ea5c88b997fa/testing/mozharness/ program finished with exit code 0 elapsedTime=1.051104 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-31 13:16:10.391569) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-31 13:16:10.391846) ========= script_repo_revision: ea5c88b997faa8be5ef6a936304a908fd0292894 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-31 13:16:10.392214) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-31 13:16:10.392484) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-31 13:16:10.520821) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 36 secs) (at 2016-05-31 13:16:10.521090) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 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', 'Larch', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.1zJO8tpGU4/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.4opkTg6n29/Listeners TMPDIR=/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 13:16:10 INFO - MultiFileLogger online at 20160531 13:16:10 in /builds/slave/test 13:16:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 13:16:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:16:10 INFO - {'append_to_log': False, 13:16:10 INFO - 'base_work_dir': '/builds/slave/test', 13:16:10 INFO - 'blob_upload_branch': 'Larch', 13:16:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:16:10 INFO - 'branch': 'Larch', 13:16:10 INFO - 'buildbot_json_path': 'buildprops.json', 13:16:10 INFO - 'config_files': ('talos/mac_config.py',), 13:16:10 INFO - 'default_actions': ('clobber', 13:16:10 INFO - 'read-buildbot-config', 13:16:10 INFO - 'download-and-extract', 13:16:10 INFO - 'populate-webroot', 13:16:10 INFO - 'create-virtualenv', 13:16:10 INFO - 'install', 13:16:10 INFO - 'run-tests'), 13:16:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:16:10 INFO - 'download_minidump_stackwalk': True, 13:16:10 INFO - 'download_symbols': 'ondemand', 13:16:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:16:10 INFO - 'tooltool.py': '/tools/tooltool.py', 13:16:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:16:10 INFO - '/tools/misc-python/virtualenv.py')}, 13:16:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:16:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:16:10 INFO - 'installer_path': 'installer.exe', 13:16:10 INFO - 'log_level': 'info', 13:16:10 INFO - 'log_name': 'talos', 13:16:10 INFO - 'log_to_console': True, 13:16:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:16:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:16:10 INFO - 'opt_config_files': (), 13:16:10 INFO - 'pip_index': False, 13:16:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:16:10 INFO - 'cmd': ('bash', 13:16:10 INFO - '-c', 13:16:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:16:10 INFO - 'enabled': True, 13:16:10 INFO - 'halt_on_failure': False, 13:16:10 INFO - 'name': 'check_screen_resolution'},), 13:16:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:16:10 INFO - 'cmd': ('bash', 13:16:10 INFO - '-c', 13:16:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:16:10 INFO - 'enabled': True, 13:16:10 INFO - 'halt_on_failure': False, 13:16:10 INFO - 'name': 'check_screen_resolution'},), 13:16:10 INFO - 'run_cmd_checks_enabled': True, 13:16:10 INFO - 'sps_profile': False, 13:16:10 INFO - 'sps_profile_interval': 0, 13:16:10 INFO - 'suite': 'dromaeojs', 13:16:10 INFO - 'system_bits': '32', 13:16:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:16:10 INFO - 'title': 't-yosemite-r7-0178', 13:16:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:16:10 INFO - 'use_talos_json': True, 13:16:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:16:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:16:10 INFO - 'work_dir': 'build'} 13:16:10 INFO - ##### 13:16:10 INFO - ##### Running clobber step. 13:16:10 INFO - ##### 13:16:10 INFO - Running pre-action listener: _resource_record_pre_action 13:16:10 INFO - Running main action method: clobber 13:16:10 INFO - rmtree: /builds/slave/test/build 13:16:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:16:12 INFO - Running post-action listener: _resource_record_post_action 13:16:12 INFO - ##### 13:16:12 INFO - ##### Finished clobber step (success) 13:16:12 INFO - ##### 13:16:12 INFO - ##### 13:16:12 INFO - ##### Running read-buildbot-config step. 13:16:12 INFO - ##### 13:16:12 INFO - Running pre-action listener: _resource_record_pre_action 13:16:12 INFO - Running main action method: read_buildbot_config 13:16:12 INFO - Using buildbot properties: 13:16:12 INFO - { 13:16:12 INFO - "project": "", 13:16:12 INFO - "product": "firefox", 13:16:12 INFO - "script_repo_revision": "production", 13:16:12 INFO - "builddir": "larch_yosemite_r7_test-dromaeojs", 13:16:12 INFO - "repository": "", 13:16:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 larch talos dromaeojs", 13:16:12 INFO - "buildid": "20160531111747", 13:16:12 INFO - "pgo_build": "False", 13:16:12 INFO - "basedir": "/builds/slave/test", 13:16:12 INFO - "buildnumber": 3, 13:16:12 INFO - "slavename": "t-yosemite-r7-0178", 13:16:12 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 13:16:12 INFO - "platform": "yosemite_r7", 13:16:12 INFO - "scheduler": "tests-larch-macosx64-talos", 13:16:12 INFO - "branch": "larch", 13:16:12 INFO - "repo_path": "projects/larch", 13:16:12 INFO - "revision": "ea5c88b997faa8be5ef6a936304a908fd0292894", 13:16:12 INFO - "stage_platform": "macosx64", 13:16:12 INFO - "builduid": "b59ecb76b9974a20abb331f19affea40", 13:16:12 INFO - "slavebuilddir": "test" 13:16:12 INFO - } 13:16:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/XqiMmXIUTEKe6Hf9uPpf5g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 13:16:12 INFO - Running post-action listener: _resource_record_post_action 13:16:12 INFO - ##### 13:16:12 INFO - ##### Finished read-buildbot-config step (success) 13:16:12 INFO - ##### 13:16:12 INFO - ##### 13:16:12 INFO - ##### Running download-and-extract step. 13:16:12 INFO - ##### 13:16:12 INFO - Running pre-action listener: _resource_record_pre_action 13:16:12 INFO - Running main action method: download_and_extract 13:16:12 INFO - mkdir: /builds/slave/test/build/tests 13:16: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')]} 13:16:12 INFO - https://queue.taskcluster.net/v1/task/XqiMmXIUTEKe6Hf9uPpf5g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 13:16:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XqiMmXIUTEKe6Hf9uPpf5g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 13:16:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XqiMmXIUTEKe6Hf9uPpf5g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 13:16:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XqiMmXIUTEKe6Hf9uPpf5g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 13:16:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XqiMmXIUTEKe6Hf9uPpf5g/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 13:16:14 INFO - Downloaded 1199 bytes. 13:16:14 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 13:16:14 INFO - Using the following test package requirements: 13:16:14 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 13:16:14 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:16:14 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 13:16:14 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:16:14 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 13:16:14 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:16:14 INFO - u'jsshell-mac.zip'], 13:16:14 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:16:14 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 13:16:14 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 13:16:14 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:16:14 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 13:16:14 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:16:14 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 13:16:14 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:16:14 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 13:16:14 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 13:16:14 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 13:16:14 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 13:16:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:16:14 INFO - https://queue.taskcluster.net/v1/task/XqiMmXIUTEKe6Hf9uPpf5g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:16:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XqiMmXIUTEKe6Hf9uPpf5g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:16:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XqiMmXIUTEKe6Hf9uPpf5g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:16:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XqiMmXIUTEKe6Hf9uPpf5g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:16:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XqiMmXIUTEKe6Hf9uPpf5g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 13:16:35 INFO - Downloaded 18687099 bytes. 13:16:35 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:16:35 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 13:16:38 INFO - Return code: 0 13:16:38 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 13:16:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:16:38 INFO - https://queue.taskcluster.net/v1/task/XqiMmXIUTEKe6Hf9uPpf5g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:16:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XqiMmXIUTEKe6Hf9uPpf5g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:16:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XqiMmXIUTEKe6Hf9uPpf5g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:16:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XqiMmXIUTEKe6Hf9uPpf5g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 13:16:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XqiMmXIUTEKe6Hf9uPpf5g/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 13:16:40 INFO - Downloaded 18687099 bytes. 13:16:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:16:40 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 13:16:44 INFO - Return code: 0 13:16:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:16:44 INFO - https://queue.taskcluster.net/v1/task/XqiMmXIUTEKe6Hf9uPpf5g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 13:16:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XqiMmXIUTEKe6Hf9uPpf5g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 13:16:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XqiMmXIUTEKe6Hf9uPpf5g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 13:16:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XqiMmXIUTEKe6Hf9uPpf5g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 13:16:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XqiMmXIUTEKe6Hf9uPpf5g/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 13:16:45 INFO - Downloaded 10834063 bytes. 13:16:45 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:16:45 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 13:16:45 INFO - Return code: 0 13:16: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')]} 13:16:45 INFO - https://queue.taskcluster.net/v1/task/XqiMmXIUTEKe6Hf9uPpf5g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 13:16:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XqiMmXIUTEKe6Hf9uPpf5g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 13:16:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XqiMmXIUTEKe6Hf9uPpf5g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 13:16:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XqiMmXIUTEKe6Hf9uPpf5g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 13:16:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XqiMmXIUTEKe6Hf9uPpf5g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 13:16:52 INFO - Downloaded 111130358 bytes. 13:16:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XqiMmXIUTEKe6Hf9uPpf5g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 13:16:52 INFO - mkdir: /builds/slave/test/properties 13:16:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:16:52 INFO - Writing to file /builds/slave/test/properties/build_url 13:16:52 INFO - Contents: 13:16:52 INFO - build_url:https://queue.taskcluster.net/v1/task/XqiMmXIUTEKe6Hf9uPpf5g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 13:16:53 INFO - Running post-action listener: _resource_record_post_action 13:16:53 INFO - Running post-action listener: set_extra_try_arguments 13:16:53 INFO - ##### 13:16:53 INFO - ##### Finished download-and-extract step (success) 13:16:53 INFO - ##### 13:16:53 INFO - ##### 13:16:53 INFO - ##### Running populate-webroot step. 13:16:53 INFO - ##### 13:16:53 INFO - Running pre-action listener: _resource_record_pre_action 13:16:53 INFO - Running main action method: populate_webroot 13:16:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:16:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:16:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:16:53 INFO - '--tppagecycles', 13:16:53 INFO - '7'], 13:16:53 INFO - 'tests': ['tsvgm']}}, 13:16:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:16:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:16:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:16:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:16:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:16:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:16:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:16:53 INFO - 'talos_options': ['--disable-e10s'], 13:16:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:16:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:16:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:16:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:16:53 INFO - 'talos_options': ['--disable-e10s'], 13:16:53 INFO - 'tests': ['damp', 'tps']}, 13:16:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:16:53 INFO - 'tests': ['damp', 'tps']}, 13:16:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:16:53 INFO - 'tests': ['dromaeo_dom']}, 13:16:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:16:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:16:53 INFO - 'tests': ['a11yr', 13:16:53 INFO - 'ts_paint', 13:16:53 INFO - 'tpaint', 13:16:53 INFO - 'sessionrestore', 13:16:53 INFO - 'sessionrestore_no_auto_restore', 13:16:53 INFO - 'tabpaint']}, 13:16:53 INFO - 'other-e10s': {'tests': ['a11yr', 13:16:53 INFO - 'ts_paint', 13:16:53 INFO - 'tpaint', 13:16:53 INFO - 'sessionrestore', 13:16:53 INFO - 'sessionrestore_no_auto_restore', 13:16:53 INFO - 'tabpaint']}, 13:16:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:16:53 INFO - 'ts_paint', 13:16:53 INFO - 'tpaint', 13:16:53 INFO - 'sessionrestore', 13:16:53 INFO - 'sessionrestore_no_auto_restore', 13:16:53 INFO - 'tabpaint']}, 13:16:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:16:53 INFO - 'ts_paint', 13:16:53 INFO - 'tpaint', 13:16:53 INFO - 'sessionrestore', 13:16:53 INFO - 'sessionrestore_no_auto_restore', 13:16:53 INFO - 'tabpaint']}, 13:16:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:16:53 INFO - 'tests': ['a11yr', 13:16:53 INFO - 'ts_paint', 13:16:53 INFO - 'tpaint', 13:16:53 INFO - 'sessionrestore', 13:16:53 INFO - 'sessionrestore_no_auto_restore', 13:16:53 INFO - 'tabpaint']}, 13:16:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:16:53 INFO - 'tests': ['a11yr', 13:16:53 INFO - 'ts_paint', 13:16:53 INFO - 'tpaint', 13:16:53 INFO - 'sessionrestore', 13:16:53 INFO - 'sessionrestore_no_auto_restore', 13:16:53 INFO - 'tabpaint']}, 13:16:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:16:53 INFO - 'tests': ['tsvgx', 13:16:53 INFO - 'tsvgr_opacity', 13:16:53 INFO - 'tart', 13:16:53 INFO - 'tscrollx', 13:16:53 INFO - 'cart']}, 13:16:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:16:53 INFO - 'tsvgr_opacity', 13:16:53 INFO - 'tart', 13:16:53 INFO - 'tscrollx', 13:16:53 INFO - 'cart']}, 13:16:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:16:53 INFO - 'talos_options': ['--disable-e10s'], 13:16:53 INFO - 'tests': ['tp5o']}, 13:16:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:16:53 INFO - 'tests': ['tp5o']}, 13:16:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:16:53 INFO - 'talos_options': ['--disable-e10s', 13:16:53 INFO - '--xperf_path', 13:16:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:16:53 INFO - 'tests': ['tp5n']}, 13:16:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:16:53 INFO - 'talos_options': ['--xperf_path', 13:16:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:16:53 INFO - 'tests': ['tp5n']}}, 13:16:53 INFO - 'talos.zip': {'path': '', 13:16:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:16:53 INFO - Running post-action listener: _resource_record_post_action 13:16:53 INFO - ##### 13:16:53 INFO - ##### Finished populate-webroot step (success) 13:16:53 INFO - ##### 13:16:53 INFO - ##### 13:16:53 INFO - ##### Running create-virtualenv step. 13:16:53 INFO - ##### 13:16:53 INFO - Running pre-action listener: _resource_record_pre_action 13:16:53 INFO - Running main action method: create_virtualenv 13:16:53 INFO - Creating virtualenv /builds/slave/test/build/venv 13:16:53 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 13:16:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:16:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:16:53 INFO - Using real prefix '/tools/python27' 13:16:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:16:54 INFO - Installing distribute.............................................................................................................................................................................................done. 13:16:56 INFO - Installing pip.................done. 13:16:56 INFO - Return code: 0 13:16:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:16:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:16:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:16:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:16:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:16:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:16:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:16:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104855a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10485b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd351c86f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046f9d30>, '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 0x10451ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1046f0b20>, '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.1zJO8tpGU4/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.4opkTg6n29/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:16:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 13:16:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 13:16:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1zJO8tpGU4/Render', 13:16:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:16:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:16:56 INFO - 'HOME': '/Users/cltbld', 13:16:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:16:56 INFO - 'LOGNAME': 'cltbld', 13:16:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:16:56 INFO - 'MOZ_NO_REMOTE': '1', 13:16:56 INFO - 'NO_EM_RESTART': '1', 13:16:56 INFO - 'PAGER': '/bin/cat', 13:16:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:16:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:16:56 INFO - 'PWD': '/builds/slave/test', 13:16:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:16:56 INFO - 'SHELL': '/bin/bash', 13:16:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4opkTg6n29/Listeners', 13:16:56 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 13:16:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:16:56 INFO - 'USER': 'cltbld', 13:16:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:16:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:16:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:16:56 INFO - 'XPC_FLAGS': '0x0', 13:16:56 INFO - 'XPC_SERVICE_NAME': '0', 13:16:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:16:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:16:57 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 13:16:57 INFO - Cleaning up... 13:16:57 INFO - Return code: 0 13:16:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:16:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:16:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:16:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:16:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:16:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:16:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:16:57 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>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104855a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10485b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd351c86f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046f9d30>, '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 0x10451ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1046f0b20>, '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.1zJO8tpGU4/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.4opkTg6n29/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:16:57 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>=3.1.1'] in /builds/slave/test/build 13:16:57 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>=3.1.1 13:16:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1zJO8tpGU4/Render', 13:16:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:16:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:16:57 INFO - 'HOME': '/Users/cltbld', 13:16:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:16:57 INFO - 'LOGNAME': 'cltbld', 13:16:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:16:57 INFO - 'MOZ_NO_REMOTE': '1', 13:16:57 INFO - 'NO_EM_RESTART': '1', 13:16:57 INFO - 'PAGER': '/bin/cat', 13:16:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:16:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:16:57 INFO - 'PWD': '/builds/slave/test', 13:16:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:16:57 INFO - 'SHELL': '/bin/bash', 13:16:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4opkTg6n29/Listeners', 13:16:57 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 13:16:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:16:57 INFO - 'USER': 'cltbld', 13:16:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:16:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:16:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:16:57 INFO - 'XPC_FLAGS': '0x0', 13:16:57 INFO - 'XPC_SERVICE_NAME': '0', 13:16:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:16:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:16:57 INFO - Downloading/unpacking psutil>=3.1.1 13:16:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:16:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:16:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:16:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:16:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:16:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:17:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:17:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:17:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:17:00 INFO - Installing collected packages: psutil 13:17:00 INFO - Running setup.py install for psutil 13:17:00 INFO - building 'psutil._psutil_osx' extension 13:17:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 13:17:01 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 13:17:01 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 13:17:01 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 13:17:01 INFO - building 'psutil._psutil_posix' extension 13:17:01 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 13:17:01 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:17:01 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:17:01 INFO - ^ 13:17:01 INFO - 1 warning generated. 13:17:01 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 13:17:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:17:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:17:02 INFO - Successfully installed psutil 13:17:02 INFO - Cleaning up... 13:17:02 INFO - Return code: 0 13:17:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:17: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')]} 13:17:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:17:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17: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')]} 13:17:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:17:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17:02 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.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104855a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10485b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd351c86f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046f9d30>, '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 0x10451ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1046f0b20>, '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.1zJO8tpGU4/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.4opkTg6n29/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:17:02 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.3'] in /builds/slave/test/build 13:17:02 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.3 13:17:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1zJO8tpGU4/Render', 13:17:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:17:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:17:02 INFO - 'HOME': '/Users/cltbld', 13:17:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:17:02 INFO - 'LOGNAME': 'cltbld', 13:17:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:17:02 INFO - 'MOZ_NO_REMOTE': '1', 13:17:02 INFO - 'NO_EM_RESTART': '1', 13:17:02 INFO - 'PAGER': '/bin/cat', 13:17:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:17:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:17:02 INFO - 'PWD': '/builds/slave/test', 13:17:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:17:02 INFO - 'SHELL': '/bin/bash', 13:17:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4opkTg6n29/Listeners', 13:17:02 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 13:17:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:17:02 INFO - 'USER': 'cltbld', 13:17:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:17:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:17:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:17:02 INFO - 'XPC_FLAGS': '0x0', 13:17:02 INFO - 'XPC_SERVICE_NAME': '0', 13:17:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:17:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:17:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:17:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:17:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:17:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:17:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:17:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:17:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:17:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:17:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:17:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:17:04 INFO - Installing collected packages: mozsystemmonitor 13:17:04 INFO - Running setup.py install for mozsystemmonitor 13:17:05 INFO - Successfully installed mozsystemmonitor 13:17:05 INFO - Cleaning up... 13:17:05 INFO - Return code: 0 13:17:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:17:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:17:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:17:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:17:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:17:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17:05 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 0x104855a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10485b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd351c86f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046f9d30>, '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 0x10451ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1046f0b20>, '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.1zJO8tpGU4/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.4opkTg6n29/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:17:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 13:17:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 13:17:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1zJO8tpGU4/Render', 13:17:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:17:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:17:05 INFO - 'HOME': '/Users/cltbld', 13:17:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:17:05 INFO - 'LOGNAME': 'cltbld', 13:17:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:17:05 INFO - 'MOZ_NO_REMOTE': '1', 13:17:05 INFO - 'NO_EM_RESTART': '1', 13:17:05 INFO - 'PAGER': '/bin/cat', 13:17:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:17:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:17:05 INFO - 'PWD': '/builds/slave/test', 13:17:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:17:05 INFO - 'SHELL': '/bin/bash', 13:17:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4opkTg6n29/Listeners', 13:17:05 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 13:17:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:17:05 INFO - 'USER': 'cltbld', 13:17:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:17:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:17:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:17:05 INFO - 'XPC_FLAGS': '0x0', 13:17:05 INFO - 'XPC_SERVICE_NAME': '0', 13:17:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:17:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:17:05 INFO - Downloading/unpacking blobuploader==1.2.4 13:17:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:17:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:17:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:17:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:17:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:17:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:17:07 INFO - Downloading blobuploader-1.2.4.tar.gz 13:17:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:17:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:17:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:17:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:17:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:17:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:17:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:17:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:17:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:17:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:17:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:17:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:17:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:17:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:17:09 INFO - Downloading docopt-0.6.1.tar.gz 13:17:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:17:09 INFO - Installing collected packages: blobuploader, requests, docopt 13:17:09 INFO - Running setup.py install for blobuploader 13:17:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:17:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:17:09 INFO - Running setup.py install for requests 13:17:09 INFO - Running setup.py install for docopt 13:17:09 INFO - Successfully installed blobuploader requests docopt 13:17:09 INFO - Cleaning up... 13:17:09 INFO - Return code: 0 13:17:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:17:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:17:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:17:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:17:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:17:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17:09 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 0x104855a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10485b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd351c86f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046f9d30>, '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 0x10451ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1046f0b20>, '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.1zJO8tpGU4/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.4opkTg6n29/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:17:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 13:17:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:17:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1zJO8tpGU4/Render', 13:17:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:17:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:17:09 INFO - 'HOME': '/Users/cltbld', 13:17:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:17:09 INFO - 'LOGNAME': 'cltbld', 13:17:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:17:09 INFO - 'MOZ_NO_REMOTE': '1', 13:17:09 INFO - 'NO_EM_RESTART': '1', 13:17:09 INFO - 'PAGER': '/bin/cat', 13:17:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:17:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:17:09 INFO - 'PWD': '/builds/slave/test', 13:17:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:17:09 INFO - 'SHELL': '/bin/bash', 13:17:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4opkTg6n29/Listeners', 13:17:09 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 13:17:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:17:09 INFO - 'USER': 'cltbld', 13:17:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:17:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:17:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:17:09 INFO - 'XPC_FLAGS': '0x0', 13:17:09 INFO - 'XPC_SERVICE_NAME': '0', 13:17:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:17:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:17:10 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-q4dCN0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:17:10 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-sTtD8v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:17:10 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-okEufC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:17:10 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-OXmxyn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:17:10 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-Oa0QdM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:17:10 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-0M6jk0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:17:10 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-R2ib83-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:17:10 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-Spk10M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:17:11 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-kVY1FY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:17:11 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-VJr56O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:17:11 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-lQRvTd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:17:11 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-29vAWf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:17:11 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-IxxKXF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:17:11 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-qSjEXg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:17:11 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-sRtjpY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:17:11 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-f_pGrZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:17:11 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-541SdR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:17:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:17:11 INFO - Running setup.py install for manifestparser 13:17:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:17:12 INFO - Running setup.py install for mozcrash 13:17:12 INFO - Running setup.py install for mozdebug 13:17:12 INFO - Running setup.py install for mozdevice 13:17:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:17:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:17:12 INFO - Running setup.py install for mozfile 13:17:12 INFO - Running setup.py install for mozhttpd 13:17:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:17:12 INFO - Running setup.py install for mozinfo 13:17:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:17:13 INFO - Running setup.py install for mozInstall 13:17:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:17:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:17:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:17:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:17:13 INFO - Running setup.py install for mozleak 13:17:13 INFO - Running setup.py install for mozlog 13:17:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:17:13 INFO - Running setup.py install for moznetwork 13:17:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:17:13 INFO - Running setup.py install for mozprocess 13:17:13 INFO - Running setup.py install for mozprofile 13:17:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:17:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:17:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:17:13 INFO - Running setup.py install for mozrunner 13:17:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:17:14 INFO - Running setup.py install for mozscreenshot 13:17:14 INFO - Running setup.py install for moztest 13:17:14 INFO - Running setup.py install for mozversion 13:17:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:17:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:17:14 INFO - Cleaning up... 13:17:14 INFO - Return code: 0 13:17:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:17:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:17:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:17:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:17:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:17:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17:14 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 0x104855a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10485b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd351c86f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046f9d30>, '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 0x10451ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1046f0b20>, '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.1zJO8tpGU4/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.4opkTg6n29/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:17:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 13:17:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:17:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1zJO8tpGU4/Render', 13:17:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:17:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:17:14 INFO - 'HOME': '/Users/cltbld', 13:17:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:17:14 INFO - 'LOGNAME': 'cltbld', 13:17:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:17:14 INFO - 'MOZ_NO_REMOTE': '1', 13:17:14 INFO - 'NO_EM_RESTART': '1', 13:17:14 INFO - 'PAGER': '/bin/cat', 13:17:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:17:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:17:14 INFO - 'PWD': '/builds/slave/test', 13:17:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:17:14 INFO - 'SHELL': '/bin/bash', 13:17:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4opkTg6n29/Listeners', 13:17:14 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 13:17:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:17:14 INFO - 'USER': 'cltbld', 13:17:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:17:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:17:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:17:14 INFO - 'XPC_FLAGS': '0x0', 13:17:14 INFO - 'XPC_SERVICE_NAME': '0', 13:17:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:17:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:17:15 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-CthH3v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 13:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:17:15 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-AhdSJk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:17:15 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-8Ncu4G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 13:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:17:15 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-VFBtwi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:17:15 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-EsmwJ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 13:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:17:15 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-7tHsIt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 13:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:17:15 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-nMdbiu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:17:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 13:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:17:15 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-nhFR57-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:17:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 13:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:17:16 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-DxGUW9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:17:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 13:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:17:16 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-nMNBFG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:17:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:17:16 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-6eLwkm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:17:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 13:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:17:16 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-JnAvUQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:17:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 13:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:17:16 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-M85fIH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:17:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 13:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:17:16 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-GHMbkl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:17:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 13:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:17:16 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-p6e5Mf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:17:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 13:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:17:16 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-2gmRzt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:17:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 13:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:17:16 INFO - Running setup.py (path:/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/pip-AsNriw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:17:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 13:17:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:17:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:17:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 13:17:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:17:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:17:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:17:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:17:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:17:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:17:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:17:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:17:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:17:19 INFO - Downloading blessings-1.6.tar.gz 13:17:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:17:19 INFO - Installing collected packages: blessings 13:17:19 INFO - Running setup.py install for blessings 13:17:19 INFO - Successfully installed blessings 13:17:19 INFO - Cleaning up... 13:17:19 INFO - Return code: 0 13:17:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:17:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:17:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:17:20 INFO - Reading from file tmpfile_stdout 13:17:20 INFO - Current package versions: 13:17:20 INFO - blessings == 1.6 13:17:20 INFO - blobuploader == 1.2.4 13:17:20 INFO - docopt == 0.6.1 13:17:20 INFO - manifestparser == 1.1 13:17:20 INFO - mozInstall == 1.12 13:17:20 INFO - mozcrash == 0.17 13:17:20 INFO - mozdebug == 0.1 13:17:20 INFO - mozdevice == 0.48 13:17:20 INFO - mozfile == 1.2 13:17:20 INFO - mozhttpd == 0.7 13:17:20 INFO - mozinfo == 0.9 13:17:20 INFO - mozleak == 0.1 13:17:20 INFO - mozlog == 3.2 13:17:20 INFO - moznetwork == 0.27 13:17:20 INFO - mozprocess == 0.22 13:17:20 INFO - mozprofile == 0.28 13:17:20 INFO - mozrunner == 6.11 13:17:20 INFO - mozscreenshot == 0.1 13:17:20 INFO - mozsystemmonitor == 0.3 13:17:20 INFO - moztest == 0.7 13:17:20 INFO - mozversion == 1.4 13:17:20 INFO - psutil == 3.1.1 13:17:20 INFO - requests == 1.2.3 13:17:20 INFO - wsgiref == 0.1.2 13:17:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:17: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')]} 13:17:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:17:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17: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')]} 13:17:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:17:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17:20 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 0x104855a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10485b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd351c86f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046f9d30>, '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 0x10451ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1046f0b20>, '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.1zJO8tpGU4/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.4opkTg6n29/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:17:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 13:17:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:17:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1zJO8tpGU4/Render', 13:17:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:17:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:17:20 INFO - 'HOME': '/Users/cltbld', 13:17:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:17:20 INFO - 'LOGNAME': 'cltbld', 13:17:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:17:20 INFO - 'MOZ_NO_REMOTE': '1', 13:17:20 INFO - 'NO_EM_RESTART': '1', 13:17:20 INFO - 'PAGER': '/bin/cat', 13:17:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:17:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:17:20 INFO - 'PWD': '/builds/slave/test', 13:17:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:17:20 INFO - 'SHELL': '/bin/bash', 13:17:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4opkTg6n29/Listeners', 13:17:20 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 13:17:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:17:20 INFO - 'USER': 'cltbld', 13:17:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:17:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:17:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:17:20 INFO - 'XPC_FLAGS': '0x0', 13:17:20 INFO - 'XPC_SERVICE_NAME': '0', 13:17:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:17:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:17:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 13:17:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 13:17:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 13:17:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 13:17:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 13:17:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 13:17:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 13:17:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 13:17:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 13:17:20 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 13:17:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 13:17:20 INFO - Cleaning up... 13:17:20 INFO - Return code: 0 13:17:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:17: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')]} 13:17:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:17:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17: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')]} 13:17:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:17:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104855a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10485b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd351c86f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046f9d30>, '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 0x10451ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1046f0b20>, '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.1zJO8tpGU4/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.4opkTg6n29/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:17: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', 'jsonschema'] in /builds/slave/test/build 13:17: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 jsonschema 13:17:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1zJO8tpGU4/Render', 13:17:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:17:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:17:20 INFO - 'HOME': '/Users/cltbld', 13:17:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:17:20 INFO - 'LOGNAME': 'cltbld', 13:17:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:17:20 INFO - 'MOZ_NO_REMOTE': '1', 13:17:20 INFO - 'NO_EM_RESTART': '1', 13:17:20 INFO - 'PAGER': '/bin/cat', 13:17:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:17:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:17:20 INFO - 'PWD': '/builds/slave/test', 13:17:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:17:20 INFO - 'SHELL': '/bin/bash', 13:17:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4opkTg6n29/Listeners', 13:17:20 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 13:17:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:17:20 INFO - 'USER': 'cltbld', 13:17:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:17:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:17:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:17:20 INFO - 'XPC_FLAGS': '0x0', 13:17:20 INFO - 'XPC_SERVICE_NAME': '0', 13:17:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:17:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:17:20 INFO - Downloading/unpacking jsonschema 13:17: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 13:17: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 13:17: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 13:17: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 13:17: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 13:17: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 13:17:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:17:23 INFO - Downloading/unpacking functools32 (from jsonschema) 13:17:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:17:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:17:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:17:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:17:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:17:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:17:23 INFO - Downloading functools32-3.2.3-2.tar.gz 13:17:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:17:23 INFO - warning: no files found matching '*.txt' 13:17:23 INFO - no previously-included directories found matching 'build' 13:17:23 INFO - no previously-included directories found matching 'dist' 13:17:23 INFO - no previously-included directories found matching '.git*' 13:17:23 INFO - Installing collected packages: jsonschema, functools32 13:17:23 INFO - Running setup.py install for functools32 13:17:23 INFO - warning: no files found matching '*.txt' 13:17:23 INFO - no previously-included directories found matching 'build' 13:17:23 INFO - no previously-included directories found matching 'dist' 13:17:23 INFO - no previously-included directories found matching '.git*' 13:17:23 INFO - Successfully installed jsonschema functools32 13:17:23 INFO - Cleaning up... 13:17:23 INFO - Return code: 0 13:17:23 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 13:17:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:17:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:17:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:17:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:17:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:17:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104855a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10485b570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd351c86f10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046f9d30>, '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 0x10451ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1046f0b20>, '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.1zJO8tpGU4/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.4opkTg6n29/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:17:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 13:17:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 13:17:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1zJO8tpGU4/Render', 13:17:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:17:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:17:23 INFO - 'HOME': '/Users/cltbld', 13:17:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:17:23 INFO - 'LOGNAME': 'cltbld', 13:17:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:17:23 INFO - 'MOZ_NO_REMOTE': '1', 13:17:23 INFO - 'NO_EM_RESTART': '1', 13:17:23 INFO - 'PAGER': '/bin/cat', 13:17:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:17:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:17:23 INFO - 'PWD': '/builds/slave/test', 13:17:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:17:23 INFO - 'SHELL': '/bin/bash', 13:17:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4opkTg6n29/Listeners', 13:17:23 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 13:17:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:17:23 INFO - 'USER': 'cltbld', 13:17:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:17:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:17:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:17:23 INFO - 'XPC_FLAGS': '0x0', 13:17:23 INFO - 'XPC_SERVICE_NAME': '0', 13:17:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:17:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:17:24 INFO - Downloading/unpacking flake8 13:17: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 13:17: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 13:17: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 13:17: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 13:17: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 13:17: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 13:17:26 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 13:17:26 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 13:17:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:17:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:17:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:17:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:17:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:17:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:17:27 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 13:17:27 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 13:17: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 13:17: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 13:17: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 13:17: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 13:17: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 13:17: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 13:17:27 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 13:17: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 13:17: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 13:17: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 13:17: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 13:17: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 13:17: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 13:17:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 13:17:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:17:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:17:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:17:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:17:27 INFO - no previously-included directories found matching 'docs/_build' 13:17:27 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 13:17:28 INFO - Running setup.py install for pep8 13:17:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:17:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:17:28 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:17:28 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:17:28 INFO - no previously-included directories found matching 'docs/_build' 13:17:28 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 13:17:28 INFO - Successfully installed flake8 mccabe pyflakes pep8 13:17:28 INFO - Cleaning up... 13:17:28 INFO - Return code: 0 13:17:28 INFO - Running post-action listener: _resource_record_post_action 13:17:28 INFO - Running post-action listener: _start_resource_monitoring 13:17:28 INFO - Starting resource monitoring. 13:17:28 INFO - ##### 13:17:28 INFO - ##### Finished create-virtualenv step (success) 13:17:28 INFO - ##### 13:17:28 INFO - ##### 13:17:28 INFO - ##### Running install step. 13:17:28 INFO - ##### 13:17:28 INFO - Running pre-action listener: _resource_record_pre_action 13:17:28 INFO - Running main action method: install 13:17:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:17:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:17:28 INFO - Reading from file tmpfile_stdout 13:17:28 INFO - Detecting whether we're running mozinstall >=1.0... 13:17:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:17:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:17:28 INFO - Reading from file tmpfile_stdout 13:17:28 INFO - Output received: 13:17:28 INFO - Usage: mozinstall [options] installer 13:17:28 INFO - Options: 13:17:28 INFO - -h, --help show this help message and exit 13:17:28 INFO - -d DEST, --destination=DEST 13:17:28 INFO - Directory to install application into. [default: 13:17:28 INFO - "/builds/slave/test"] 13:17:28 INFO - --app=APP Application being installed. [default: firefox] 13:17:28 INFO - mkdir: /builds/slave/test/build/application 13:17:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 13:17:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 13:18:02 INFO - Reading from file tmpfile_stdout 13:18:02 INFO - Output received: 13:18:02 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 13:18:02 INFO - Running post-action listener: _resource_record_post_action 13:18:02 INFO - ##### 13:18:02 INFO - ##### Finished install step (success) 13:18:02 INFO - ##### 13:18:02 INFO - ##### 13:18:02 INFO - ##### Running run-tests step. 13:18:02 INFO - ##### 13:18:02 INFO - Running pre-action listener: _resource_record_pre_action 13:18:02 INFO - Running main action method: run_tests 13:18:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:18:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:18:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:18:02 INFO - 2016-05-31 13:18:02.727 screenresolution[1646:8530] starting screenresolution argv=screenresolution get 13:18:02 INFO - 2016-05-31 13:18:02.729 screenresolution[1646:8530] Display 0: 1600x1200x32@60 13:18:02 INFO - 2016-05-31 13:18:02.735 screenresolution[1647:8532] starting screenresolution argv=screenresolution list 13:18:02 INFO - Available Modes on Display 0 13:18:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:18:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:18:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:18:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:18:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:18:02 INFO - Intel Iris: 13:18:02 INFO - Chipset Model: Intel Iris 13:18:02 INFO - Type: GPU 13:18:02 INFO - Bus: Built-In 13:18:02 INFO - VRAM (Dynamic, Max): 1536 MB 13:18:02 INFO - Vendor: Intel (0x8086) 13:18:02 INFO - Device ID: 0x0a2e 13:18:02 INFO - Revision ID: 0x0009 13:18:02 INFO - Displays: 13:18:02 INFO - Display: 13:18:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:18:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:18:02 INFO - Display Serial Number: 1600x1200 60 13:18:02 INFO - Main Display: Yes 13:18:02 INFO - Mirror: Off 13:18:02 INFO - Online: Yes 13:18:02 INFO - Rotation: Supported 13:18:02 INFO - Return code: 0 13:18:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:18:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:18:02 INFO - Python 2.7.3 13:18:02 INFO - Return code: 0 13:18:02 INFO - grabbing minidump binary from tooltool 13:18: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')]} 13:18:02 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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1046f9d30>, '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 0x10451ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1046f0b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:18:02 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 13:18:02 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 13:18:02 INFO - Calling ['/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'] with output_timeout 600 13:18:03 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:18:03 INFO - Return code: 0 13:18:03 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:18:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:18:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:18:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:18:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:18:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 13:18:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 13:18:04 INFO - Return code: 0 13:18:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XqiMmXIUTEKe6Hf9uPpf5g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0178', '--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 13:18:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XqiMmXIUTEKe6Hf9uPpf5g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0178 --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 13:18:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1zJO8tpGU4/Render', 13:18:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:18:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:18:04 INFO - 'HOME': '/Users/cltbld', 13:18:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:18:04 INFO - 'LOGNAME': 'cltbld', 13:18:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:18:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:18:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:18:04 INFO - 'MOZ_NO_REMOTE': '1', 13:18:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:18:04 INFO - 'NO_EM_RESTART': '1', 13:18:04 INFO - 'PAGER': '/bin/cat', 13:18:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:18:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:18:04 INFO - 'PWD': '/builds/slave/test', 13:18:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:18:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:18:04 INFO - 'SHELL': '/bin/bash', 13:18:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4opkTg6n29/Listeners', 13:18:04 INFO - 'TMPDIR': '/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/', 13:18:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:18:04 INFO - 'USER': 'cltbld', 13:18:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:18:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:18:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:18:04 INFO - 'XPC_FLAGS': '0x0', 13:18:04 INFO - 'XPC_SERVICE_NAME': '0', 13:18:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:18:04 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XqiMmXIUTEKe6Hf9uPpf5g/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0178', '--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 13:18:04 INFO - mozversion application_buildid: 20160531111747 13:18:04 INFO - mozversion application_changeset: ea5c88b997faa8be5ef6a936304a908fd0292894 13:18:04 INFO - mozversion application_display_name: Nightly 13:18:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:18:04 INFO - mozversion application_name: Firefox 13:18:04 INFO - mozversion application_remotingname: firefox 13:18:04 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 13:18:04 INFO - mozversion application_vendor: Mozilla 13:18:04 INFO - mozversion application_version: 49.0a1 13:18:04 INFO - mozversion platform_buildid: 20160531111747 13:18:04 INFO - mozversion platform_changeset: ea5c88b997faa8be5ef6a936304a908fd0292894 13:18:04 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 13:18:04 INFO - mozversion platform_version: 49.0a1 13:18:04 INFO - using testdate: 1464725884 13:18:04 INFO - actual date: 1464725884 13:18:04 INFO - starting webserver on 'localhost:49287' 13:18:04 INFO - SUITE-START | Running 2 tests 13:18:04 INFO - TEST-START | dromaeo_css 13:18:04 INFO - operating with platform_type : mac_ 13:18:04 INFO - Initialising browser for dromaeo_css test... 13:18:04 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/tmpBxKhyb/profile http://localhost:49287/getInfo.html) 13:18:06 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 13:18:06 INFO - PROCESS | 1665 | colorDepth:24 13:18:06 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 13:18:06 INFO - PROCESS | 1665 | __metrics 13:18:06 INFO - PROCESS | 1665 | 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" 13:18:06 ERROR - PROCESS | 1665 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 13:18:07 INFO - TEST-INFO | 1665: exit 0 13:18:07 INFO - Browser initialized. 13:18:07 INFO - Running cycle 1/1 for dromaeo_css test... 13:18:07 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/tmpBxKhyb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:18:19 INFO - PROCESS | 1668 | RSS: Main: 241807360 13:18:19 INFO - PROCESS | 1668 | 13:19:10 INFO - PROCESS | 1668 | 1464725950933 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 13:21:22 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49287/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49287/tests/dromaeo/cssquery-ext.html) 13:21:23 INFO - PROCESS | 1668 | RSS: Main: 345878528 13:21:23 INFO - PROCESS | 1668 | 13:24:26 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49287/tests/dromaeo/cssquery-ext.html (next: http://localhost:49287/tests/dromaeo/cssquery-jquery.html) 13:24:27 INFO - PROCESS | 1668 | RSS: Main: 380850176 13:24:27 INFO - PROCESS | 1668 | 13:27:29 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49287/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49287/tests/dromaeo/cssquery-mootools.html) 13:27:30 INFO - PROCESS | 1668 | RSS: Main: 375283712 13:27:30 INFO - PROCESS | 1668 | 13:30:32 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49287/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49287/tests/dromaeo/cssquery-prototype.html) 13:30:33 INFO - PROCESS | 1668 | RSS: Main: 370683904 13:30:33 INFO - PROCESS | 1668 | 13:33:36 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49287/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49287/tests/dromaeo/cssquery-yui.html) 13:33:37 INFO - PROCESS | 1668 | RSS: Main: 336728064 13:33:37 INFO - PROCESS | 1668 | 13:36:40 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49287/tests/dromaeo/cssquery-yui.html (next: http://localhost:49287/tests/dromaeo/cssquery-dojo.html) 13:36:40 INFO - PROCESS | 1668 | RSS: Main: 378048512 13:36:40 INFO - PROCESS | 1668 | 13:36:40 INFO - PROCESS | 1668 | __start_tp_report 13:36:40 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 13:36:40 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 13:36:40 INFO - PROCESS | 1668 | |i|pagename|runs| 13:36:40 INFO - PROCESS | 1668 | |0;dojo.html;3464.133966508373;3475.7914525128494;3484.4599087141487;3514.525539052227;3561.0919215600034;5648.983183027053;5693.54451643868;5726.484616384525;5778.942210577899;5822.3886491918265;5716.1997320375185;5756.395578464264;5800.738966746496;5806.912896306549;5858.062709966406;5173.741312934357;5198.0383629028565;5255.421903590602;5279.815206467774;5386.48828361305;5349.22436246744;5381.408045115034;5382.811601593945;5403.486668766461;5426.972865135669;5192.1692529195525;5207.427183009866;5234.659747116425;5288.762005709733;5303.36359636846;5297.655652382621;5300.761465734013;5304.761285742151;5304.92042619361;5311.62818602698;5146.330976973007;5178.896422071575;5183.844484665466;5213.791448342062;5255.1329030709885;5626.915596266059;5699.515541179005;5739.3399759027825;5778.162166485836;5780.710964451802;6295.811125666238;6361.9045714314325;6366.713497892606;6368.554201205918;6374.203224596926;5193.844184674465;6212.72042758077;6258.24901011877;6331.715072821734;6331.936680633181;5950.9107363389585;5970.761169553212;5974.014287642533;6017.097435384728;6027.819165425044;6279.529035322369;6314.71583778731;6329.7468101275435;6367.426931576088;6389.744410223539;6146.924288249538;6207.16203312547;6213.2233470816145;6287.088372186007;6315.936840631627;6359.523035772243;6365.649889256136;6367.968160159169;6373.426391624776;6389.712462939179;6119.929102025404;6206;6323.399277068671;6325;6356.809295721136;6267.9373206267355;6301.212348456443;6343.111964324999;6344.936550634435;6350.936490635035;4958.330625365529;5015.398152221756;5017.147084995559;5021.774020169101;5045.899082018339;4460.264056430653;4466.9279372950905;4481.7983190756495;4528.88677783058;4541.364209010741;5802.448767367094;5815.680137592473;5816.796412125555;5873.119032145127;5879.265091863517;6307.779227727007;6336.429721325103;6358.936410635834;6365;6382.425581697669;5699.8860022800145;5722.828315150552;5779.0464573344925;5788.27646544182;5835.79574714883;5415.404305526389;5481.150421684645;5500.532454741392;5515.448455154453;5537.529310008614;6357.491400688026;6390.392912673288;6406.807795766134;6423.710933007932;6426.550141490051;6195.132681424694;6253.812385628438;6310.400511951358;6324.3991820776955;6366.140571022853;6372.267189273293;6385.776497822554;6385.96807015962;6402.935970640234;6421.165248517663;6217.968910155601;6286.937130628635;6289.119523266655;6303.117563541016;6316.842078948063;6182.474489668249;6228.750849965951;6306;6311.27420346666;6347.841303967438;6138.109974053827;6187.504999599932;6190.566660333733;6195.94668906296;6199.16311297969;6156.415140561639;6169.444749972345;6179.28938172116;6185.072239164161;6188.412100850412;5691.260136182435;5916.822495325147;5955.821325360246;5988.221531200916;6014.72933717975;6241.78153764616;6245.687715614292;6250.124982502544;6263.185785847811;6274.435300822948;6048.576599637983;6073.5748497604745;6082.148499210026;6091.329953705001;6109.725062372115 13:36:40 INFO - PROCESS | 1668 | |1;ext.html;492.6846818036455;495.1236311728241;512.1779543832151;529.7695502456429;530.7001544127569;30529.542056869137;30914.91797787078;31799.523007154854;31889.521657175163;31919.68080319196;32759.50860737091;33102.834485827596;43181;44763.552364476425;44836;8540.402171847973;9669.17811985982;9709.417434953926;9710.028997100304;9720;9610.951945240264;9760.951195244013;9762.90237097627;9767.755806104833;9777.12005919467;8675.783105422352;8897.510636914967;11549.53801847925;11770.529178832836;11810.173287869853;11869.991050760653;11950.46222919962;11964.880351196463;11975;11977.760444791098;8526.786830329229;10420.68737937863;11479.483423245974;11763.529458821637;11851.585194518237;31416.37167256665;33024.834875825705;33102.50346244808;40185.59814401848;41315.38026929599;3483.076984599083;3493.510908472816;3579.3020361029608;3668.944965825515;3855.363864962278;32128.5180722287;32928.6707132928;33155.50266746001;33332;33337.33325333504;3235.6117265928006;3274.3123943971796;3298.175456135966;3394.4568868980846;3416.521686963802;494213;497192;497260;497514;497790;33788.83105584457;33833.8158164468;34616.96149115531;66873.66252674919;67552.3244767546;36002;36301.81849090791;36392;36473.452898206546;36480.63519364772;15146.469873554373;15301.693966120616;15304.234788260543;15306.464273750365;15315.31081101353;12845.743085138245;12941.158824676366;12949.676258093434;12955.287459189834;12962.611121666365;7180.210176880539;7423.962880185564;7924.524528528307;7958.403119765925;8043.879341809878;3238.4332741770095;3318.634950155481;3322.4517954537714;3326.151831283056;3354.26206234628;916.4959272400154;1004.6985904228702;1011.3173607814842;1047.272145858621;1092.3336764573603;29026.27434314159;30812.845935770627;30946.84526577353;31375.37249255048;31543.684563154075;1036.1141224253156;1097.9780404391868;1172.2966220267772;1173.7711146326385;1206.927584344942;15864.60338491547;16367.91816040912;16679.332826687038;16841.157942102847;16924.492265232064;644901;654031;656431;658811;659800;53693.46306536963;61306.69346653238;62485;62547;62664.68667656633;1425.3728359521776;1438.9640258993609;1460.5253292679922;1464.1654257073367;1469.8603632655309;31338.843305783324;32133.678663213068;32299.838500808284;33000.33999319952;33077.338453231285;52702.73648631732;53557.46442535525;53855.19217211823;53905.73047134739;54035.729821350644;668032;674671;675743;855835;859824;26816.59775103414;27028;27081.1875643731;27113.72886271191;27226.45547089087;46730.53269467398;47830.760846195546;47940.28089578729;48584;48960;38682.35811702614;38690.80654596709;38933;39183.41224881572;39664.80167599144;38044.42933356057;38351.80824095861;38403.03992400212;38965.80517097397;39197.6080239194;37707.62292377041;38166.80916595399;38442.80778596089;38570.421443678926;38638.613613863505;27900.72099278981;31749.365012699156;32888.34223315568;34408.48387274143;34444.48333275053;24653.260402187963;24973.250802475955;24986;25616.231513054638;25691 13:36:40 INFO - PROCESS | 1668 | |2;jquery.html;342.6179809512394;382.6479638732366;383.32917394559513;386.9918861366139;406.16533024634384;19208.519787005316;20677.379678609665;21450.57098858022;21496.355109346707;21500;20288.69566956493;20922.372328830123;21014;21023.68464473034;21488.247911323106;5162.497401427491;5762.9135562966585;6062.120992456091;6066.908996365058;6077.088436734481;4103.425520427142;4383.517813040563;6289.308176100625;6627.801165965005;6640.867182656344;6346.079818426326;6592.241892182388;6636.369544893228;6654.068430419745;6693.196816382017;5967.224260846106;6332.936670633281;6641.402273795357;6649.50128740344;6664.666766661671;3753.4557489163917;5385.138377859563;5748.16651585522;5750.712464376807;5793.971030144864;25969.610455843176;26280.47439051208;26305.3423664408;26650.66746662659;26691;2175.9891200544052;2253.6957510736006;2256.0637335505744;2311.15642790382;2329.7903188712926;15375.923120384326;15681.843181568152;15905.840941590668;15939.681206375808;16221.432249871315;1537.3927298716944;1817.3820900893627;1848.741176235328;1897.1083590712344;1933.081786151581;114529.42735286271;118486;120335.39832300958;120540.39729801382;120717;9630.32587718867;10773.730656733644;11066.889331106586;11070.335779853074;11221.495032723547;13861.722765544633;13879.930600346934;13891.583252502442;13921.025528213131;13940.790888136686;7584.241575842482;8467.364947628952;8665.480071195749;8682.131786821394;8797.38418310725;8610.612522436506;8628;8638.352123590756;8716.997545282247;8755;5857.188549496181;6545.639989800607;6764.72941082361;6769.627670478171;6773.254941956381;1759.6040890799468;1777.6533575952824;1854.4622059602834;1876.1838600208953;1890.357278525308;848.499385362639;952.7475219066953;1010.2372708605103;1034.3276870034388;1056.9101626361846;18816.717749233772;22033.00851461656;22043.559128817335;22277.77722222757;22293.44266393321;862.7498025572639;985.980280394388;991.8958509356557;1011.8178727829062;1132.1452303510796;8573.528455934858;11313.547458101584;11384.487698053608;11827.704307392383;12002.699932501582;567654;571272;584017;586136;588307;109686.45156774165;109958.45020774845;110837;111590.44204778924;123074;983.3460748602242;983.7688143286322;1003.7440452684518;1010.0858722855827;1039.542601255445;15409.383624654889;19302.903485482482;19666.50833729168;19701.21195152178;19915.900420498383;48093.5190648103;48407;48512.272315915994;49491.75254123707;51092.23361649652;52605.473945260055;68306.65846670735;69390.3060969404;69413.30586694069;70303;7912.564808935564;8000.959995199987;8077.676892924453;8286.751397458085;8456.788580285542;10936.671899843017;11399.772004560029;12586.87413125857;12831.486740530274;13365.732685346433;11297.943510282395;11322.094232461548;11389.487473063566;11448.141389395661;11502;11324.263922844988;11365.54537818511;11452;11833.349165795964;11925.165238433226;11624.883751162719;11649.767004660029;11880.405979701154;11892.940535297268;11896.988755955841;8179.182081791773;8181;8208.876866846882;8249.29880960101;8271.793205169679;9002.729918102468;9873.815142182984;11591.5942942;11621.418929053345;11803.82294265604 13:36:40 INFO - PROCESS | 1668 | |3;mootools.html;1404.0943591383552;1568.5529624057147;1601.959951001225;1832.5052235896308;1986.4239370582532;8065.475744076638;8919.509426981513;9213.401128926615;9235.492047937369;9407.200387967014;8927;9228.769280767967;9233.722988310345;9243.260539156869;9245.537723113852;2124.7662757096705;2201.1305534313938;2201.4496375906024;2399.6640470334123;2433.0875921529428;1961.7940116287798;2196.7034450349156;2220.0897631970897;2456.0421435640114;2459.188467805629;2428.659987601737;2428.757124287566;2431.6960379952507;2436.4517983453757;2437.0617312334703;2368.7986521145635;2418.3349578865777;2418.5162967406413;2419.395151212188;2419.7822196002267;2120.8197303229244;2293.3005433342823;2360.197532838843;2369.5853225685437;2377.6314671225987;9092.045335239836;9176.8623470648;9178.954105229432;9179.770505737342;9203.631854725803;5350.571954243651;5494.752736126884;5498.945010549884;5502.80740174096;5505.256790333294;3532;3667.6699097081123;3734.047817806442;3737.831797569089;3746.5691445483844;1072.7961687279392;1100.1693721240492;1106.2753896198003;1109.112709832131;1120.6021862238952;12213.328266945315;12236.204646697935;12243.32661703606;12246.693832654166;12254.326012069332;4733.100710864925;4869.683470574401;4937.308776771255;4951.678140920829;4955.702657840541;10186.796264074677;10255.948810237982;10284.485775711184;10301.9484902575;10335.483225838829;5500.092484740053;5504.091824848935;5505.889882202334;5506.972465137648;5513.558915286768;4482.731036137842;4902.289168070681;4935.215316736215;4957.950420495821;5017.9964007198705;3802.2015376770637;4029.4560234368573;4143.943294459894;4179.853705120307;4187.664986801049;1110.7001109700336;1112.5494174859195;1323.4772264955288;1352.377906163176;1354.4920654754467;822.9259366657029;877.9473231606124;918.9432153024076;926.1988380051207;933.262722449271;5967.791127310524;6143.477804386588;6144.9553575892205;6159.414855588712;6216.471599914058;856.8243510080418;968.4431451915177;993.4055840376236;1024.2164743970868;1071.2875937501474;3936.645701886787;3978.6273735561763;4027.416024676464;4100.794960251976;4111.8972025698995;10987.450627468754;11237.438128093563;11352.262102963281;11444.771104578029;11456.599019034295;11029.779404411867;11256;11386.08911287095;11400.973912347928;11405.429728513707;7605.619719014137;8200.958995205105;8403.243708066304;8607.139286071468;8612.354073444516;5873.794417195378;5994.760209591742;5998.190244316961;6052;6131.63210207389;9998.950005249928;10092.24308176875;10359.32664376813;10398.428086455015;10413.166946644402;10510.106640935606;10574.312669676447;10598.417087060287;10604.734881628021;10609.416482093558;7761.883571746315;7791.805204869924;7814.101378341336;7943.880841787493;7971.880421793794;8524.318054555744;8526.616302266537;8623.568821559022;8639.82720345577;8641;7682.769516914502;7739.7678069658;7748.147703752696;7936.722214722457;7967.123616402076;7536.472446928662;7618.771436856903;7692.884606730791;7797.571133587707;7889.960550197212;7142.214356420685;7190.388816950409;7264.382527485223;7268.636568171464;7273.309035641606;5117.641765076409;5129.666571672829;5134.178531434953;5178.533931946143;5204.115300399017;5136.178211486144;5175.534201921845;5202.635815492879;5299.947000529945;5385.13837785968 13:36:40 INFO - PROCESS | 1668 | |4;prototype.html;250.9924702258932;260.31926512170674;313.2044606698985;318.76570720520414;326.16664422400754;4501.549845015501;4764.952350476494;4979.751012449381;5380.811671591495;5471.139812463762;4623.190941585226;4951.876203094925;5267.26258323834;5422.566194704424;5426.2674538937235;3149.8740050397955;3668.6881615062753;3876.8061596920184;3982.5021872265966;4161.313383291753;4094.1811637672436;4119.382092686091;4135.31767258402;4150.315197992328;4155.33514637658;3602.126894015111;4089.7750623715683;4092.774897380643;4122.454079720105;4164.895877603054;3922.9607703923025;3980.263651224504;3988.880333589997;4091.672666186698;4127.442795222667;3563;3565.94651080234;3587.6142365327028;3597.478365636968;3622.3660859349507;4317.97841010796;4370.541093185201;5134.845954621367;5178.974105129488;5199.402068762065;4206.663466922639;4523.864284071483;4617.884552886171;4745.576327101856;4759.381280433556;4727.574518293336;5669;5671.00757367459;5684.8294551163535;5687.886242275173;3515.2969406118877;3738.4018557030745;3796.4305354196817;3796.487474191004;3996.760194388346;5799.179511689932;5836.649801011953;5877.67672777997;5975.790847320323;6007.695767327952;3548.5741710994844;4010.8796736097966;4040.8585699500377;4049.6714389226313;4052.3313653246882;3470.6355832637714;3650.452432135201;4128.339465685476;4137.627613514798;4143.875683729493;4558.766204559808;4571.382863313476;4672.929906051412;4727.834525791581;4750.61995040396;4952.959878425499;5650.463205995424;5687.7156142192725;5696.287964004499;5724.77100915967;1275.2412314672754;1408.260663151833;1410.3230449384353;1410.6684929041667;1414.4412956881974;700.1563116444672;794.606669698503;847.8934989838197;849.0278630967507;850.503114518452;4765;5097.515736005073;5180.280352814802;5219.3736751589295;5234.01661053196;5605;5872;5880.411958804171;5886.676232807151;5987.191729116601;4709.646776491777;5096.700341412916;5297.761600727977;5299.350324837581;5320.962412329636;2648.860989774377;2760.751532362097;2939.4268117717265;2946.9410611787644;3069.907902762921;4632.490426053131;5511.338639363302;5640.830775076754;6066.48434883031;6128.050152226412;5202;6025.578209525291;6026.7287972040485;6031.868170303356;6043.184170137063;5247.317848679723;5291.3385826771655;5846.736896839568;5867.970660146757;6094.542909281821;5196.376434827845;5441.619086663895;5514.897020595977;5548.612846788303;5856.882862342644;5113.8977220454635;5731.942680573307;5755.3021858552665;5777;5787.768489260382;5126.769295381642;5395.325584301962;5811.302643682785;6060.393960603904;6130.908036379368;3638.599754027108;3932.525303011371;4000.879973600797;4010.1177740896956;4019.3167161582014;5304.708241046779;5746.253746253747;5816.331121921034;5887.793927212527;5911.172435858897;4798.568128868418;5700.714964251853;5744.827655170351;5753.683547404933;5784.595078344475;4845.709257444564;5200.947990520198;5289.518934698291;5454.699991500505;5745.683987380734;4929.433115191799;5524.8342549723575;5537.640053396516;5588;5588.497035266845;4899.118158731462;5157.535821776059;5367.731613419392;5581.972090139523;5590.804321848878;4949.881574607974;5222.712750798591;5598.524125449219;5663.490285874291;5686.43135686428 13:36:40 INFO - PROCESS | 1668 | |5;yui.html;724.4277021153288;818.1695578987329;821.7534739578125;840.953747543885;858.9656413743451;14638.560843174708;15323.387064517434;15335.616609584766;15341.309641066151;15346;13921.58235252944;13965.88272938165;13992.580222593313;13993.370298336575;13998.30008499576;702.8629417263622;727.3381223086992;866.9783255418602;890.6570970176497;891.5854127830557;875.3697337916722;888.3914518554778;891.162307431016;891.8260939116875;892.7366426904078;945.2343601682659;945.7021038372925;945.9858102128474;946.1768261612407;949.7340744591526;727.6616373386374;914.4787471141452;941.6139382853031;945.0596656326999;949.7815502434479;701.5685353507556;702.5995182745837;702.8769965256059;703.3704834173386;703.7536862098276;14497.56507304772;14533.636659083502;14542.70914581713;14562.927185364006;14564.927175364055;11492.712682182928;13122.2126672399;13303.467861285633;13450.461981520728;13464.730705385935;10598.364098154058;11813.822792658117;11968.34174120423;11971.162018658697;12593.559225427158;4409.919330743956;4411.382406463065;4412.338149277602;4413;4534.523874993144;80506.59746701288;81178.59410702909;82007.58996204981;83052.92367690796;83197.58401207955;9970.551325190383;10371.585136594602;10758.354498730101;10815.621453249256;10954.671359859218;20315.18739250443;20380.184792608427;20539.178432862816;20564.074616642287;20573.58852822965;7681.155072941972;7724.304812566784;7769.601471735029;7776.455648104578;7883.921160788434;4363.267881003627;4383.408239887638;4433.733975961452;4453.710598163316;4549.954500454953;3454.4472884338384;3542.4509201073874;3544.025393016941;3544.858205671796;3547.6629720176543;662.7481557008306;685.6845850908538;687.8864987277143;688.6900894597451;691.6818263598801;614.7950017964839;646.6023395611664;659.5877576514679;666;670.168990451846;8763.211310982042;9034.593443295067;9075.81848363029;9172.6789562365;9332.066793320613;609.9481544068715;625.2746813696091;639.146739103293;641.2273210780997;650.5771248688296;4259.488861336597;4273.857886997495;4289.463817022872;4323.891902702458;4402.669799765031;82879.92800180044;85550.86122846979;85731;92345;97959.51020244995;80196.5990170057;80324.19675803283;82114.3577128466;82208.58895705602;82263;69990.95104062997;70621.64689176521;72214.27785722181;73375.99999999894;74258.51482970419;9146.268298536233;9523.619055237712;9684.128428441474;9725.854112188465;9818.509074546102;43948.681539553865;44837.551624483334;46138.538614613426;46825.76587117043;46842.53157468382;63351;64032.35967640264;64076.679616603484;64102;64154.35845641484;12472.625821225378;12497.12520123619;12676.28755204667;12887.742245155232;13467.12463689857;11441.771164576829;14177.14937103777;14504;14672.266386680836;14756.262186890826;10789.784204316027;13300.854077960088;13334.06469564553;13546.661333466742;13675.589732308046;13336.733265334446;13349.532766353519;13701.109427887553;13767.173969561858;13777;12816.231026138461;12980.870191297967;12981.091323607257;13077.934610326887;13477.32613369308;10947.890521094687;10987.83518247211;11026.889731102587;11103.944480277547;11504.53981840717;10795.514201861091;11131.320480174829;11233.831492527455;11242.044426223863;11258.31125331207 13:36:40 INFO - PROCESS | 1668 | __end_tp_report 13:36:40 INFO - PROCESS | 1668 | __start_cc_report 13:36:40 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,1151 13:36:40 INFO - PROCESS | 1668 | __end_cc_report 13:36:40 INFO - PROCESS | 1668 | __startTimestamp1464727000717__endTimestamp 13:36:40 INFO - PROCESS | 1668 | 13:36:40 INFO - PROCESS | 1668 | ------- Summary: start ------- 13:36:40 INFO - PROCESS | 1668 | Number of tests: 6 13:36:40 INFO - PROCESS | 1668 | 13:36:40 INFO - PROCESS | 1668 | [#0] dojo.html Cycles:165 Average:5826.55 Median:6073.57 stddev:637.84 (10.5%) stddev-sans-first:612.28 13:36:40 INFO - PROCESS | 1668 | Values: 3464.1 3475.8 3484.5 3514.5 3561.1 5649.0 5693.5 5726.5 5778.9 5822.4 5716.2 5756.4 5800.7 5806.9 5858.1 5173.7 5198.0 5255.4 5279.8 5386.5 5349.2 5381.4 5382.8 5403.5 5427.0 5192.2 5207.4 5234.7 5288.8 5303.4 5297.7 5300.8 5304.8 5304.9 5311.6 5146.3 5178.9 5183.8 5213.8 5255.1 5626.9 5699.5 5739.3 5778.2 5780.7 6295.8 6361.9 6366.7 6368.6 6374.2 5193.8 6212.7 6258.2 6331.7 6331.9 5950.9 5970.8 5974.0 6017.1 6027.8 6279.5 6314.7 6329.7 6367.4 6389.7 6146.9 6207.2 6213.2 6287.1 6315.9 6359.5 6365.6 6368.0 6373.4 6389.7 6119.9 6206.0 6323.4 6325.0 6356.8 6267.9 6301.2 6343.1 6344.9 6350.9 4958.3 5015.4 5017.1 5021.8 5045.9 4460.3 4466.9 4481.8 4528.9 4541.4 5802.4 5815.7 5816.8 5873.1 5879.3 6307.8 6336.4 6358.9 6365.0 6382.4 5699.9 5722.8 5779.0 5788.3 5835.8 5415.4 5481.2 5500.5 5515.4 5537.5 6357.5 6390.4 6406.8 6423.7 6426.6 6195.1 6253.8 6310.4 6324.4 6366.1 6372.3 6385.8 6386.0 6402.9 6421.2 6218.0 6286.9 6289.1 6303.1 6316.8 6182.5 6228.8 6306.0 6311.3 6347.8 6138.1 6187.5 6190.6 6195.9 6199.2 6156.4 6169.4 6179.3 6185.1 6188.4 5691.3 5916.8 5955.8 5988.2 6014.7 6241.8 6245.7 6250.1 6263.2 6274.4 6048.6 6073.6 6082.1 6091.3 6109.7 13:36:40 INFO - PROCESS | 1668 | 13:36:40 INFO - PROCESS | 1668 | [#1] ext.html Cycles:180 Average:74084.16 Median:18912.50 stddev:173032.13 (914.9%) stddev-sans-first:173429.31 13:36:40 INFO - PROCESS | 1668 | Values: 492.7 495.1 512.2 529.8 530.7 30529.5 30914.9 31799.5 31889.5 31919.7 32759.5 33102.8 43181.0 44763.6 44836.0 8540.4 9669.2 9709.4 9710.0 9720.0 9611.0 9761.0 9762.9 9767.8 9777.1 8675.8 8897.5 11549.5 11770.5 11810.2 11870.0 11950.5 11964.9 11975.0 11977.8 8526.8 10420.7 11479.5 11763.5 11851.6 31416.4 33024.8 33102.5 40185.6 41315.4 3483.1 3493.5 3579.3 3668.9 3855.4 32128.5 32928.7 33155.5 33332.0 33337.3 3235.6 3274.3 3298.2 3394.5 3416.5 494213.0 497192.0 497260.0 497514.0 497790.0 33788.8 33833.8 34617.0 66873.7 67552.3 36002.0 36301.8 36392.0 36473.5 36480.6 15146.5 15301.7 15304.2 15306.5 15315.3 12845.7 12941.2 12949.7 12955.3 12962.6 7180.2 7424.0 7924.5 7958.4 8043.9 3238.4 3318.6 3322.5 3326.2 3354.3 916.5 1004.7 1011.3 1047.3 1092.3 29026.3 30812.8 30946.8 31375.4 31543.7 1036.1 1098.0 1172.3 1173.8 1206.9 15864.6 16367.9 16679.3 16841.2 16924.5 644901.0 654031.0 656431.0 658811.0 659800.0 53693.5 61306.7 62485.0 62547.0 62664.7 1425.4 1439.0 1460.5 1464.2 1469.9 31338.8 32133.7 32299.8 33000.3 33077.3 52702.7 53557.5 53855.2 53905.7 54035.7 668032.0 674671.0 675743.0 855835.0 859824.0 26816.6 27028.0 27081.2 27113.7 27226.5 46730.5 47830.8 47940.3 48584.0 48960.0 38682.4 38690.8 38933.0 39183.4 39664.8 38044.4 38351.8 38403.0 38965.8 39197.6 37707.6 38166.8 38442.8 38570.4 38638.6 27900.7 31749.4 32888.3 34408.5 34444.5 24653.3 24973.3 24986.0 25616.2 25691.0 13:36:40 INFO - PROCESS | 1668 | 13:36:40 INFO - PROCESS | 1668 | [#2] jquery.html Cycles:180 Average:33981.56 Median:2254.88 stddev:96346.81 (4272.8%) stddev-sans-first:96583.98 13:36:40 INFO - PROCESS | 1668 | Values: 342.6 382.6 383.3 387.0 406.2 19208.5 20677.4 21450.6 21496.4 21500.0 20288.7 20922.4 21014.0 21023.7 21488.2 5162.5 5762.9 6062.1 6066.9 6077.1 4103.4 4383.5 6289.3 6627.8 6640.9 6346.1 6592.2 6636.4 6654.1 6693.2 5967.2 6332.9 6641.4 6649.5 6664.7 3753.5 5385.1 5748.2 5750.7 5794.0 25969.6 26280.5 26305.3 26650.7 26691.0 2176.0 2253.7 2256.1 2311.2 2329.8 15375.9 15681.8 15905.8 15939.7 16221.4 1537.4 1817.4 1848.7 1897.1 1933.1 114529.4 118486.0 120335.4 120540.4 120717.0 9630.3 10773.7 11066.9 11070.3 11221.5 13861.7 13879.9 13891.6 13921.0 13940.8 7584.2 8467.4 8665.5 8682.1 8797.4 8610.6 8628.0 8638.4 8717.0 8755.0 5857.2 6545.6 6764.7 6769.6 6773.3 1759.6 1777.7 1854.5 1876.2 1890.4 848.5 952.7 1010.2 1034.3 1056.9 18816.7 22033.0 22043.6 22277.8 22293.4 862.7 986.0 991.9 1011.8 1132.1 8573.5 11313.5 11384.5 11827.7 12002.7 567654.0 571272.0 584017.0 586136.0 588307.0 109686.5 109958.5 110837.0 111590.4 123074.0 983.3 983.8 1003.7 1010.1 1039.5 15409.4 19302.9 19666.5 19701.2 19915.9 48093.5 48407.0 48512.3 49491.8 51092.2 52605.5 68306.7 69390.3 69413.3 70303.0 7912.6 8001.0 8077.7 8286.8 8456.8 10936.7 11399.8 12586.9 12831.5 13365.7 11297.9 11322.1 11389.5 11448.1 11502.0 11324.3 11365.5 11452.0 11833.3 11925.2 11624.9 11649.8 11880.4 11892.9 11897.0 8179.2 8181.0 8208.9 8249.3 8271.8 9002.7 9873.8 11591.6 11621.4 11803.8 13:36:40 INFO - PROCESS | 1668 | 13:36:40 INFO - PROCESS | 1668 | [#3] mootools.html Cycles:180 Average:5926.47 Median:4936.26 stddev:3366.98 (68.2%) stddev-sans-first:3359.27 13:36:40 INFO - PROCESS | 1668 | Values: 1404.1 1568.6 1602.0 1832.5 1986.4 8065.5 8919.5 9213.4 9235.5 9407.2 8927.0 9228.8 9233.7 9243.3 9245.5 2124.8 2201.1 2201.4 2399.7 2433.1 1961.8 2196.7 2220.1 2456.0 2459.2 2428.7 2428.8 2431.7 2436.5 2437.1 2368.8 2418.3 2418.5 2419.4 2419.8 2120.8 2293.3 2360.2 2369.6 2377.6 9092.0 9176.9 9179.0 9179.8 9203.6 5350.6 5494.8 5498.9 5502.8 5505.3 3532.0 3667.7 3734.0 3737.8 3746.6 1072.8 1100.2 1106.3 1109.1 1120.6 12213.3 12236.2 12243.3 12246.7 12254.3 4733.1 4869.7 4937.3 4951.7 4955.7 10186.8 10255.9 10284.5 10301.9 10335.5 5500.1 5504.1 5505.9 5507.0 5513.6 4482.7 4902.3 4935.2 4958.0 5018.0 3802.2 4029.5 4143.9 4179.9 4187.7 1110.7 1112.5 1323.5 1352.4 1354.5 822.9 877.9 918.9 926.2 933.3 5967.8 6143.5 6145.0 6159.4 6216.5 856.8 968.4 993.4 1024.2 1071.3 3936.6 3978.6 4027.4 4100.8 4111.9 10987.5 11237.4 11352.3 11444.8 11456.6 11029.8 11256.0 11386.1 11401.0 11405.4 7605.6 8201.0 8403.2 8607.1 8612.4 5873.8 5994.8 5998.2 6052.0 6131.6 9999.0 10092.2 10359.3 10398.4 10413.2 10510.1 10574.3 10598.4 10604.7 10609.4 7761.9 7791.8 7814.1 7943.9 7971.9 8524.3 8526.6 8623.6 8639.8 8641.0 7682.8 7739.8 7748.1 7936.7 7967.1 7536.5 7618.8 7692.9 7797.6 7890.0 7142.2 7190.4 7264.4 7268.6 7273.3 5117.6 5129.7 5134.2 5178.5 5204.1 5136.2 5175.5 5202.6 5299.9 5385.1 13:36:40 INFO - PROCESS | 1668 | 13:36:40 INFO - PROCESS | 1668 | [#4] prototype.html Cycles:180 Average:4567.18 Median:5130.81 stddev:1405.83 (27.4%) stddev-sans-first:1371.94 13:36:40 INFO - PROCESS | 1668 | Values: 251.0 260.3 313.2 318.8 326.2 4501.5 4765.0 4979.8 5380.8 5471.1 4623.2 4951.9 5267.3 5422.6 5426.3 3149.9 3668.7 3876.8 3982.5 4161.3 4094.2 4119.4 4135.3 4150.3 4155.3 3602.1 4089.8 4092.8 4122.5 4164.9 3923.0 3980.3 3988.9 4091.7 4127.4 3563.0 3565.9 3587.6 3597.5 3622.4 4318.0 4370.5 5134.8 5179.0 5199.4 4206.7 4523.9 4617.9 4745.6 4759.4 4727.6 5669.0 5671.0 5684.8 5687.9 3515.3 3738.4 3796.4 3796.5 3996.8 5799.2 5836.6 5877.7 5975.8 6007.7 3548.6 4010.9 4040.9 4049.7 4052.3 3470.6 3650.5 4128.3 4137.6 4143.9 4558.8 4571.4 4672.9 4727.8 4750.6 4953.0 5650.5 5687.7 5696.3 5724.8 1275.2 1408.3 1410.3 1410.7 1414.4 700.2 794.6 847.9 849.0 850.5 4765.0 5097.5 5180.3 5219.4 5234.0 5605.0 5872.0 5880.4 5886.7 5987.2 4709.6 5096.7 5297.8 5299.4 5321.0 2648.9 2760.8 2939.4 2946.9 3069.9 4632.5 5511.3 5640.8 6066.5 6128.1 5202.0 6025.6 6026.7 6031.9 6043.2 5247.3 5291.3 5846.7 5868.0 6094.5 5196.4 5441.6 5514.9 5548.6 5856.9 5113.9 5731.9 5755.3 5777.0 5787.8 5126.8 5395.3 5811.3 6060.4 6130.9 3638.6 3932.5 4000.9 4010.1 4019.3 5304.7 5746.3 5816.3 5887.8 5911.2 4798.6 5700.7 5744.8 5753.7 5784.6 4845.7 5200.9 5289.5 5454.7 5745.7 4929.4 5524.8 5537.6 5588.0 5588.5 4899.1 5157.5 5367.7 5582.0 5590.8 4949.9 5222.7 5598.5 5663.5 5686.4 13:36:40 INFO - PROCESS | 1668 | 13:36:40 INFO - PROCESS | 1668 | [#5] yui.html Cycles:180 Average:18674.29 Median:25344.25 stddev:25363.12 (100.1%) stddev-sans-first:25398.45 13:36:40 INFO - PROCESS | 1668 | Values: 724.4 818.2 821.8 841.0 859.0 14638.6 15323.4 15335.6 15341.3 15346.0 13921.6 13965.9 13992.6 13993.4 13998.3 702.9 727.3 867.0 890.7 891.6 875.4 888.4 891.2 891.8 892.7 945.2 945.7 946.0 946.2 949.7 727.7 914.5 941.6 945.1 949.8 701.6 702.6 702.9 703.4 703.8 14497.6 14533.6 14542.7 14562.9 14564.9 11492.7 13122.2 13303.5 13450.5 13464.7 10598.4 11813.8 11968.3 11971.2 12593.6 4409.9 4411.4 4412.3 4413.0 4534.5 80506.6 81178.6 82007.6 83052.9 83197.6 9970.6 10371.6 10758.4 10815.6 10954.7 20315.2 20380.2 20539.2 20564.1 20573.6 7681.2 7724.3 7769.6 7776.5 7883.9 4363.3 4383.4 4433.7 4453.7 4550.0 3454.4 3542.5 3544.0 3544.9 3547.7 662.7 685.7 687.9 688.7 691.7 614.8 646.6 659.6 666.0 670.2 8763.2 9034.6 9075.8 9172.7 9332.1 609.9 625.3 639.1 641.2 650.6 4259.5 4273.9 4289.5 4323.9 4402.7 82879.9 85550.9 85731.0 92345.0 97959.5 80196.6 80324.2 82114.4 82208.6 82263.0 69991.0 70621.6 72214.3 73376.0 74258.5 9146.3 9523.6 9684.1 9725.9 9818.5 43948.7 44837.6 46138.5 46825.8 46842.5 63351.0 64032.4 64076.7 64102.0 64154.4 12472.6 12497.1 12676.3 12887.7 13467.1 11441.8 14177.1 14504.0 14672.3 14756.3 10789.8 13300.9 13334.1 13546.7 13675.6 13336.7 13349.5 13701.1 13767.2 13777.0 12816.2 12980.9 12981.1 13077.9 13477.3 10947.9 10987.8 11026.9 11103.9 11504.5 10795.5 11131.3 11233.8 11242.0 11258.3 13:36:40 INFO - PROCESS | 1668 | -------- Summary: end -------- 13:36:40 INFO - PROCESS | 1668 | 13:36:41 INFO - TEST-INFO | 1668: exit 0 13:36:41 INFO - TEST-OK | dromaeo_css | took 1116887ms 13:36:41 INFO - TEST-START | kraken 13:36:41 INFO - operating with platform_type : mac_ 13:36:41 INFO - Initialising browser for kraken test... 13:36:41 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/tmptxYBOY/profile http://localhost:49287/getInfo.html) 13:36:43 INFO - PROCESS | 1676 | __metrics Screen width/height:1600/1200 13:36:43 INFO - PROCESS | 1676 | colorDepth:24 13:36:43 INFO - PROCESS | 1676 | Browser inner width/height: 1024/689 13:36:43 INFO - PROCESS | 1676 | __metrics 13:36:43 INFO - PROCESS | 1676 | 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" 13:36:43 ERROR - PROCESS | 1676 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 13:36:44 INFO - TEST-INFO | 1676: exit 0 13:36:44 INFO - Browser initialized. 13:36:44 INFO - Running cycle 1/1 for kraken test... 13:36:44 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/T/tmptxYBOY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:36:56 INFO - PROCESS | 1677 | RSS: Main: 241930240 13:36:56 INFO - PROCESS | 1677 | 13:36:58 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49287/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49287/tests/kraken/driver.html?testName=audio-beat-detection) 13:36:59 INFO - PROCESS | 1677 | RSS: Main: 292069376 13:36:59 INFO - PROCESS | 1677 | 13:37:02 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49287/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49287/tests/kraken/driver.html?testName=audio-dft) 13:37:03 INFO - PROCESS | 1677 | RSS: Main: 302268416 13:37:03 INFO - PROCESS | 1677 | 13:37:06 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49287/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49287/tests/kraken/driver.html?testName=audio-fft) 13:37:07 INFO - PROCESS | 1677 | RSS: Main: 391839744 13:37:07 INFO - PROCESS | 1677 | 13:37:09 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49287/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49287/tests/kraken/driver.html?testName=audio-oscillator) 13:37:09 INFO - PROCESS | 1677 | RSS: Main: 291766272 13:37:09 INFO - PROCESS | 1677 | 13:37:12 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49287/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49287/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:37:12 INFO - PROCESS | 1677 | RSS: Main: 295686144 13:37:12 INFO - PROCESS | 1677 | 13:37:15 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49287/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49287/tests/kraken/driver.html?testName=imaging-darkroom) 13:37:16 INFO - PROCESS | 1677 | RSS: Main: 331628544 13:37:16 INFO - PROCESS | 1677 | 13:37:18 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49287/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49287/tests/kraken/driver.html?testName=imaging-desaturate) 13:37:19 INFO - PROCESS | 1677 | RSS: Main: 334405632 13:37:19 INFO - PROCESS | 1677 | 13:37:22 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49287/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49287/tests/kraken/driver.html?testName=json-parse-financial) 13:37:23 INFO - PROCESS | 1677 | RSS: Main: 334098432 13:37:23 INFO - PROCESS | 1677 | 13:37:24 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49287/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49287/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:37:25 INFO - PROCESS | 1677 | RSS: Main: 312066048 13:37:25 INFO - PROCESS | 1677 | 13:37:27 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49287/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49287/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:37:27 INFO - PROCESS | 1677 | RSS: Main: 319410176 13:37:27 INFO - PROCESS | 1677 | 13:37:29 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49287/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49287/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:37:30 INFO - PROCESS | 1677 | RSS: Main: 323411968 13:37:30 INFO - PROCESS | 1677 | 13:37:33 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49287/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49287/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:37:34 INFO - PROCESS | 1677 | RSS: Main: 325074944 13:37:34 INFO - PROCESS | 1677 | 13:37:37 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49287/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49287/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:37:37 INFO - PROCESS | 1677 | RSS: Main: 325951488 13:37:37 INFO - PROCESS | 1677 | 13:37:39 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49287/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49287/tests/kraken/driver.html?testName=ai-astar) 13:37:39 INFO - PROCESS | 1677 | RSS: Main: 369778688 13:37:39 INFO - PROCESS | 1677 | 13:37:39 INFO - PROCESS | 1677 | __start_tp_report 13:37:39 INFO - PROCESS | 1677 | _x_x_mozilla_page_load 13:37:39 INFO - PROCESS | 1677 | _x_x_mozilla_page_load_details 13:37:39 INFO - PROCESS | 1677 | |i|pagename|runs| 13:37:39 INFO - PROCESS | 1677 | |0;ai-astar;114;106;112;112;102;121;165;165;113;112 13:37:39 INFO - PROCESS | 1677 | |1;audio-beat-detection;154;119;118;167;124;116;135;117;140;123 13:37:39 INFO - PROCESS | 1677 | |2;audio-dft;184;183;182;183;183;181;220;187;185;184 13:37:39 INFO - PROCESS | 1677 | |3;audio-fft;77;72;58;87;58;58;64;62;82;57 13:37:39 INFO - PROCESS | 1677 | |4;audio-oscillator;82;106;121;72;77;69;97;66;66;103 13:37:39 INFO - PROCESS | 1677 | |5;imaging-gaussian-blur;90;86;89;87;90;86;91;87;90;86 13:37:39 INFO - PROCESS | 1677 | |6;imaging-darkroom;93;95;95;94;93;95;95;95;95;95 13:37:39 INFO - PROCESS | 1677 | |7;imaging-desaturate;133;141;88;85;86;85;86;85;85;85 13:37:39 INFO - PROCESS | 1677 | |8;json-parse-financial;57;57;55;55;56;56;56;56;55;56 13:37:39 INFO - PROCESS | 1677 | |9;json-stringify-tinderbox;66;84;72;77;72;71;70;71;68;71 13:37:39 INFO - PROCESS | 1677 | |10;stanford-crypto-aes;85;87;86;86;84;87;86;88;123;88 13:37:39 INFO - PROCESS | 1677 | |11;stanford-crypto-ccm;141;139;153;142;143;145;151;134;164;161 13:37:39 INFO - PROCESS | 1677 | |12;stanford-crypto-pbkdf2;166;223;219;169;164;163;166;166;164;163 13:37:39 INFO - PROCESS | 1677 | |13;stanford-crypto-sha256-iterative;64;66;67;67;66;65;68;65;66;63 13:37:39 INFO - PROCESS | 1677 | __end_tp_report 13:37:39 INFO - PROCESS | 1677 | __start_cc_report 13:37:39 INFO - PROCESS | 1677 | _x_x_mozilla_cycle_collect,1994 13:37:39 INFO - PROCESS | 1677 | __end_cc_report 13:37:39 INFO - PROCESS | 1677 | __startTimestamp1464727059685__endTimestamp 13:37:39 INFO - PROCESS | 1677 | 13:37:39 INFO - PROCESS | 1677 | ------- Summary: start ------- 13:37:39 INFO - PROCESS | 1677 | Number of tests: 14 13:37:39 INFO - PROCESS | 1677 | 13:37:39 INFO - PROCESS | 1677 | [#0] ai-astar Cycles:10 Average:122.20 Median:113.50 stddev:23.09 (20.3%) stddev-sans-first:24.30 13:37:39 INFO - PROCESS | 1677 | Values: 114.0 106.0 112.0 112.0 102.0 121.0 165.0 165.0 113.0 112.0 13:37:39 INFO - PROCESS | 1677 | 13:37:39 INFO - PROCESS | 1677 | [#1] audio-beat-detection Cycles:10 Average:131.30 Median:129.50 stddev:17.54 (13.5%) stddev-sans-first:16.57 13:37:39 INFO - PROCESS | 1677 | Values: 154.0 119.0 118.0 167.0 124.0 116.0 135.0 117.0 140.0 123.0 13:37:39 INFO - PROCESS | 1677 | 13:37:39 INFO - PROCESS | 1677 | [#2] audio-dft Cycles:10 Average:187.20 Median:184.00 stddev:11.64 (6.3%) stddev-sans-first:12.29 13:37:39 INFO - PROCESS | 1677 | Values: 184.0 183.0 182.0 183.0 183.0 181.0 220.0 187.0 185.0 184.0 13:37:39 INFO - PROCESS | 1677 | 13:37:39 INFO - PROCESS | 1677 | [#3] audio-fft Cycles:10 Average:67.50 Median:68.00 stddev:11.18 (16.4%) stddev-sans-first:11.31 13:37:39 INFO - PROCESS | 1677 | Values: 77.0 72.0 58.0 87.0 58.0 58.0 64.0 62.0 82.0 57.0 13:37:39 INFO - PROCESS | 1677 | 13:37:39 INFO - PROCESS | 1677 | [#4] audio-oscillator Cycles:10 Average:85.90 Median:70.50 stddev:19.48 (27.6%) stddev-sans-first:20.62 13:37:39 INFO - PROCESS | 1677 | Values: 82.0 106.0 121.0 72.0 77.0 69.0 97.0 66.0 66.0 103.0 13:37:39 INFO - PROCESS | 1677 | 13:37:39 INFO - PROCESS | 1677 | [#5] imaging-gaussian-blur Cycles:10 Average:88.20 Median:89.50 stddev:1.99 (2.2%) stddev-sans-first:2.00 13:37:39 INFO - PROCESS | 1677 | Values: 90.0 86.0 89.0 87.0 90.0 86.0 91.0 87.0 90.0 86.0 13:37:39 INFO - PROCESS | 1677 | 13:37:39 INFO - PROCESS | 1677 | [#6] imaging-darkroom Cycles:10 Average:94.50 Median:95.00 stddev:0.85 (0.9%) stddev-sans-first:0.71 13:37:39 INFO - PROCESS | 1677 | Values: 93.0 95.0 95.0 94.0 93.0 95.0 95.0 95.0 95.0 95.0 13:37:39 INFO - PROCESS | 1677 | 13:37:39 INFO - PROCESS | 1677 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:85.00 stddev:21.76 (25.6%) stddev-sans-first:18.48 13:37:39 INFO - PROCESS | 1677 | Values: 133.0 141.0 88.0 85.0 86.0 85.0 86.0 85.0 85.0 85.0 13:37:39 INFO - PROCESS | 1677 | 13:37:39 INFO - PROCESS | 1677 | [#8] json-parse-financial Cycles:10 Average:55.90 Median:56.00 stddev:0.74 (1.3%) stddev-sans-first:0.67 13:37:39 INFO - PROCESS | 1677 | Values: 57.0 57.0 55.0 55.0 56.0 56.0 56.0 56.0 55.0 56.0 13:37:39 INFO - PROCESS | 1677 | 13:37:39 INFO - PROCESS | 1677 | [#9] json-stringify-tinderbox Cycles:10 Average:72.20 Median:71.50 stddev:5.03 (7.0%) stddev-sans-first:4.81 13:37:39 INFO - PROCESS | 1677 | Values: 66.0 84.0 72.0 77.0 72.0 71.0 70.0 71.0 68.0 71.0 13:37:39 INFO - PROCESS | 1677 | 13:37:39 INFO - PROCESS | 1677 | [#10] stanford-crypto-aes Cycles:10 Average:90.00 Median:86.50 stddev:11.66 (13.5%) stddev-sans-first:12.23 13:37:39 INFO - PROCESS | 1677 | Values: 85.0 87.0 86.0 86.0 84.0 87.0 86.0 88.0 123.0 88.0 13:37:39 INFO - PROCESS | 1677 | 13:37:39 INFO - PROCESS | 1677 | [#11] stanford-crypto-ccm Cycles:10 Average:147.30 Median:148.00 stddev:9.72 (6.6%) stddev-sans-first:10.04 13:37:39 INFO - PROCESS | 1677 | Values: 141.0 139.0 153.0 142.0 143.0 145.0 151.0 134.0 164.0 161.0 13:37:39 INFO - PROCESS | 1677 | 13:37:39 INFO - PROCESS | 1677 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.30 Median:166.00 stddev:23.65 (14.2%) stddev-sans-first:24.78 13:37:39 INFO - PROCESS | 1677 | Values: 166.0 223.0 219.0 169.0 164.0 163.0 166.0 166.0 164.0 163.0 13:37:39 INFO - PROCESS | 1677 | 13:37:39 INFO - PROCESS | 1677 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.70 Median:66.00 stddev:1.49 (2.3%) stddev-sans-first:1.45 13:37:39 INFO - PROCESS | 1677 | Values: 64.0 66.0 67.0 67.0 66.0 65.0 68.0 65.0 66.0 63.0 13:37:39 INFO - PROCESS | 1677 | -------- Summary: end -------- 13:37:39 INFO - PROCESS | 1677 | 13:37:40 INFO - TEST-INFO | 1677: exit 0 13:37:40 INFO - TEST-OK | kraken | took 59181ms 13:37:40 INFO - SUITE-END | took 1176s 13:37:40 INFO - Completed test suite (00:19:36) 13:37:40 INFO - javascript benchmark 13:37:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3464.133966508373, 3475.7914525128494, 3484.4599087141487, 3514.525539052227, 3561.0919215600034, 5648.983183027053, 5693.54451643868, 5726.484616384525, 5778.942210577899, 5822.3886491918265, 5716.1997320375185, 5756.395578464264, 5800.738966746496, 5806.912896306549, 5858.062709966406, 5173.741312934357, 5198.0383629028565, 5255.421903590602, 5279.815206467774, 5386.48828361305, 5349.22436246744, 5381.408045115034, 5382.811601593945, 5403.486668766461, 5426.972865135669, 5192.1692529195525, 5207.427183009866, 5234.659747116425, 5288.762005709733, 5303.36359636846, 5297.655652382621, 5300.761465734013, 5304.761285742151, 5304.92042619361, 5311.62818602698, 5146.330976973007, 5178.896422071575, 5183.844484665466, 5213.791448342062, 5255.1329030709885, 5626.915596266059, 5699.515541179005, 5739.3399759027825, 5778.162166485836, 5780.710964451802, 6295.811125666238, 6361.9045714314325, 6366.713497892606, 6368.554201205918, 6374.203224596926, 5193.844184674465, 6212.72042758077, 6258.24901011877, 6331.715072821734, 6331.936680633181, 5950.9107363389585, 5970.761169553212, 5974.014287642533, 6017.097435384728, 6027.819165425044, 6279.529035322369, 6314.71583778731, 6329.7468101275435, 6367.426931576088, 6389.744410223539, 6146.924288249538, 6207.16203312547, 6213.2233470816145, 6287.088372186007, 6315.936840631627, 6359.523035772243, 6365.649889256136, 6367.968160159169, 6373.426391624776, 6389.712462939179, 6119.929102025404, 6206.0, 6323.399277068671, 6325.0, 6356.809295721136, 6267.9373206267355, 6301.212348456443, 6343.111964324999, 6344.936550634435, 6350.936490635035, 4958.330625365529, 5015.398152221756, 5017.147084995559, 5021.774020169101, 5045.899082018339, 4460.264056430653, 4466.9279372950905, 4481.7983190756495, 4528.88677783058, 4541.364209010741, 5802.448767367094, 5815.680137592473, 5816.796412125555, 5873.119032145127, 5879.265091863517, 6307.779227727007, 6336.429721325103, 6358.936410635834, 6365.0, 6382.425581697669, 5699.8860022800145, 5722.828315150552, 5779.0464573344925, 5788.27646544182, 5835.79574714883, 5415.404305526389, 5481.150421684645, 5500.532454741392, 5515.448455154453, 5537.529310008614, 6357.491400688026, 6390.392912673288, 6406.807795766134, 6423.710933007932, 6426.550141490051, 6195.132681424694, 6253.812385628438, 6310.400511951358, 6324.3991820776955, 6366.140571022853, 6372.267189273293, 6385.776497822554, 6385.96807015962, 6402.935970640234, 6421.165248517663, 6217.968910155601, 6286.937130628635, 6289.119523266655, 6303.117563541016, 6316.842078948063, 6182.474489668249, 6228.750849965951, 6306.0, 6311.27420346666, 6347.841303967438, 6138.109974053827, 6187.504999599932, 6190.566660333733, 6195.94668906296, 6199.16311297969, 6156.415140561639, 6169.444749972345, 6179.28938172116, 6185.072239164161, 6188.412100850412, 5691.260136182435, 5916.822495325147, 5955.821325360246, 5988.221531200916, 6014.72933717975, 6241.78153764616, 6245.687715614292, 6250.124982502544, 6263.185785847811, 6274.435300822948, 6048.576599637983, 6073.5748497604745, 6082.148499210026, 6091.329953705001, 6109.725062372115], "value": 5786.423483833742, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [492.6846818036455, 495.1236311728241, 512.1779543832151, 529.7695502456429, 530.7001544127569, 30529.542056869137, 30914.91797787078, 31799.523007154854, 31889.521657175163, 31919.68080319196, 32759.50860737091, 33102.834485827596, 43181.0, 44763.552364476425, 44836.0, 8540.402171847973, 9669.17811985982, 9709.417434953926, 9710.028997100304, 9720.0, 9610.951945240264, 9760.951195244013, 9762.90237097627, 9767.755806104833, 9777.12005919467, 8675.783105422352, 8897.510636914967, 11549.53801847925, 11770.529178832836, 11810.173287869853, 11869.991050760653, 11950.46222919962, 11964.880351196463, 11975.0, 11977.760444791098, 8526.786830329229, 10420.68737937863, 11479.483423245974, 11763.529458821637, 11851.585194518237, 31416.37167256665, 33024.834875825705, 33102.50346244808, 40185.59814401848, 41315.38026929599, 3483.076984599083, 3493.510908472816, 3579.3020361029608, 3668.944965825515, 3855.363864962278, 32128.5180722287, 32928.6707132928, 33155.50266746001, 33332.0, 33337.33325333504, 3235.6117265928006, 3274.3123943971796, 3298.175456135966, 3394.4568868980846, 3416.521686963802, 494213.0, 497192.0, 497260.0, 497514.0, 497790.0, 33788.83105584457, 33833.8158164468, 34616.96149115531, 66873.66252674919, 67552.3244767546, 36002.0, 36301.81849090791, 36392.0, 36473.452898206546, 36480.63519364772, 15146.469873554373, 15301.693966120616, 15304.234788260543, 15306.464273750365, 15315.31081101353, 12845.743085138245, 12941.158824676366, 12949.676258093434, 12955.287459189834, 12962.611121666365, 7180.210176880539, 7423.962880185564, 7924.524528528307, 7958.403119765925, 8043.879341809878, 3238.4332741770095, 3318.634950155481, 3322.4517954537714, 3326.151831283056, 3354.26206234628, 916.4959272400154, 1004.6985904228702, 1011.3173607814842, 1047.272145858621, 1092.3336764573603, 29026.27434314159, 30812.845935770627, 30946.84526577353, 31375.37249255048, 31543.684563154075, 1036.1141224253156, 1097.9780404391868, 1172.2966220267772, 1173.7711146326385, 1206.927584344942, 15864.60338491547, 16367.91816040912, 16679.332826687038, 16841.157942102847, 16924.492265232064, 644901.0, 654031.0, 656431.0, 658811.0, 659800.0, 53693.46306536963, 61306.69346653238, 62485.0, 62547.0, 62664.68667656633, 1425.3728359521776, 1438.9640258993609, 1460.5253292679922, 1464.1654257073367, 1469.8603632655309, 31338.843305783324, 32133.678663213068, 32299.838500808284, 33000.33999319952, 33077.338453231285, 52702.73648631732, 53557.46442535525, 53855.19217211823, 53905.73047134739, 54035.729821350644, 668032.0, 674671.0, 675743.0, 855835.0, 859824.0, 26816.59775103414, 27028.0, 27081.1875643731, 27113.72886271191, 27226.45547089087, 46730.53269467398, 47830.760846195546, 47940.28089578729, 48584.0, 48960.0, 38682.35811702614, 38690.80654596709, 38933.0, 39183.41224881572, 39664.80167599144, 38044.42933356057, 38351.80824095861, 38403.03992400212, 38965.80517097397, 39197.6080239194, 37707.62292377041, 38166.80916595399, 38442.80778596089, 38570.421443678926, 38638.613613863505, 27900.72099278981, 31749.365012699156, 32888.34223315568, 34408.48387274143, 34444.48333275053, 24653.260402187963, 24973.250802475955, 24986.0, 25616.231513054638, 25691.0], "value": 19101.125155225753, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [342.6179809512394, 382.6479638732366, 383.32917394559513, 386.9918861366139, 406.16533024634384, 19208.519787005316, 20677.379678609665, 21450.57098858022, 21496.355109346707, 21500.0, 20288.69566956493, 20922.372328830123, 21014.0, 21023.68464473034, 21488.247911323106, 5162.497401427491, 5762.9135562966585, 6062.120992456091, 6066.908996365058, 6077.088436734481, 4103.425520427142, 4383.517813040563, 6289.308176100625, 6627.801165965005, 6640.867182656344, 6346.079818426326, 6592.241892182388, 6636.369544893228, 6654.068430419745, 6693.196816382017, 5967.224260846106, 6332.936670633281, 6641.402273795357, 6649.50128740344, 6664.666766661671, 3753.4557489163917, 5385.138377859563, 5748.16651585522, 5750.712464376807, 5793.971030144864, 25969.610455843176, 26280.47439051208, 26305.3423664408, 26650.66746662659, 26691.0, 2175.9891200544052, 2253.6957510736006, 2256.0637335505744, 2311.15642790382, 2329.7903188712926, 15375.923120384326, 15681.843181568152, 15905.840941590668, 15939.681206375808, 16221.432249871315, 1537.3927298716944, 1817.3820900893627, 1848.741176235328, 1897.1083590712344, 1933.081786151581, 114529.42735286271, 118486.0, 120335.39832300958, 120540.39729801382, 120717.0, 9630.32587718867, 10773.730656733644, 11066.889331106586, 11070.335779853074, 11221.495032723547, 13861.722765544633, 13879.930600346934, 13891.583252502442, 13921.025528213131, 13940.790888136686, 7584.241575842482, 8467.364947628952, 8665.480071195749, 8682.131786821394, 8797.38418310725, 8610.612522436506, 8628.0, 8638.352123590756, 8716.997545282247, 8755.0, 5857.188549496181, 6545.639989800607, 6764.72941082361, 6769.627670478171, 6773.254941956381, 1759.6040890799468, 1777.6533575952824, 1854.4622059602834, 1876.1838600208953, 1890.357278525308, 848.499385362639, 952.7475219066953, 1010.2372708605103, 1034.3276870034388, 1056.9101626361846, 18816.717749233772, 22033.00851461656, 22043.559128817335, 22277.77722222757, 22293.44266393321, 862.7498025572639, 985.980280394388, 991.8958509356557, 1011.8178727829062, 1132.1452303510796, 8573.528455934858, 11313.547458101584, 11384.487698053608, 11827.704307392383, 12002.699932501582, 567654.0, 571272.0, 584017.0, 586136.0, 588307.0, 109686.45156774165, 109958.45020774845, 110837.0, 111590.44204778924, 123074.0, 983.3460748602242, 983.7688143286322, 1003.7440452684518, 1010.0858722855827, 1039.542601255445, 15409.383624654889, 19302.903485482482, 19666.50833729168, 19701.21195152178, 19915.900420498383, 48093.5190648103, 48407.0, 48512.272315915994, 49491.75254123707, 51092.23361649652, 52605.473945260055, 68306.65846670735, 69390.3060969404, 69413.30586694069, 70303.0, 7912.564808935564, 8000.959995199987, 8077.676892924453, 8286.751397458085, 8456.788580285542, 10936.671899843017, 11399.772004560029, 12586.87413125857, 12831.486740530274, 13365.732685346433, 11297.943510282395, 11322.094232461548, 11389.487473063566, 11448.141389395661, 11502.0, 11324.263922844988, 11365.54537818511, 11452.0, 11833.349165795964, 11925.165238433226, 11624.883751162719, 11649.767004660029, 11880.405979701154, 11892.940535297268, 11896.988755955841, 8179.182081791773, 8181.0, 8208.876866846882, 8249.29880960101, 8271.793205169679, 9002.729918102468, 9873.815142182984, 11591.5942942, 11621.418929053345, 11803.82294265604], "value": 9759.341326552372, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1404.0943591383552, 1568.5529624057147, 1601.959951001225, 1832.5052235896308, 1986.4239370582532, 8065.475744076638, 8919.509426981513, 9213.401128926615, 9235.492047937369, 9407.200387967014, 8927.0, 9228.769280767967, 9233.722988310345, 9243.260539156869, 9245.537723113852, 2124.7662757096705, 2201.1305534313938, 2201.4496375906024, 2399.6640470334123, 2433.0875921529428, 1961.7940116287798, 2196.7034450349156, 2220.0897631970897, 2456.0421435640114, 2459.188467805629, 2428.659987601737, 2428.757124287566, 2431.6960379952507, 2436.4517983453757, 2437.0617312334703, 2368.7986521145635, 2418.3349578865777, 2418.5162967406413, 2419.395151212188, 2419.7822196002267, 2120.8197303229244, 2293.3005433342823, 2360.197532838843, 2369.5853225685437, 2377.6314671225987, 9092.045335239836, 9176.8623470648, 9178.954105229432, 9179.770505737342, 9203.631854725803, 5350.571954243651, 5494.752736126884, 5498.945010549884, 5502.80740174096, 5505.256790333294, 3532.0, 3667.6699097081123, 3734.047817806442, 3737.831797569089, 3746.5691445483844, 1072.7961687279392, 1100.1693721240492, 1106.2753896198003, 1109.112709832131, 1120.6021862238952, 12213.328266945315, 12236.204646697935, 12243.32661703606, 12246.693832654166, 12254.326012069332, 4733.100710864925, 4869.683470574401, 4937.308776771255, 4951.678140920829, 4955.702657840541, 10186.796264074677, 10255.948810237982, 10284.485775711184, 10301.9484902575, 10335.483225838829, 5500.092484740053, 5504.091824848935, 5505.889882202334, 5506.972465137648, 5513.558915286768, 4482.731036137842, 4902.289168070681, 4935.215316736215, 4957.950420495821, 5017.9964007198705, 3802.2015376770637, 4029.4560234368573, 4143.943294459894, 4179.853705120307, 4187.664986801049, 1110.7001109700336, 1112.5494174859195, 1323.4772264955288, 1352.377906163176, 1354.4920654754467, 822.9259366657029, 877.9473231606124, 918.9432153024076, 926.1988380051207, 933.262722449271, 5967.791127310524, 6143.477804386588, 6144.9553575892205, 6159.414855588712, 6216.471599914058, 856.8243510080418, 968.4431451915177, 993.4055840376236, 1024.2164743970868, 1071.2875937501474, 3936.645701886787, 3978.6273735561763, 4027.416024676464, 4100.794960251976, 4111.8972025698995, 10987.450627468754, 11237.438128093563, 11352.262102963281, 11444.771104578029, 11456.599019034295, 11029.779404411867, 11256.0, 11386.08911287095, 11400.973912347928, 11405.429728513707, 7605.619719014137, 8200.958995205105, 8403.243708066304, 8607.139286071468, 8612.354073444516, 5873.794417195378, 5994.760209591742, 5998.190244316961, 6052.0, 6131.63210207389, 9998.950005249928, 10092.24308176875, 10359.32664376813, 10398.428086455015, 10413.166946644402, 10510.106640935606, 10574.312669676447, 10598.417087060287, 10604.734881628021, 10609.416482093558, 7761.883571746315, 7791.805204869924, 7814.101378341336, 7943.880841787493, 7971.880421793794, 8524.318054555744, 8526.616302266537, 8623.568821559022, 8639.82720345577, 8641.0, 7682.769516914502, 7739.7678069658, 7748.147703752696, 7936.722214722457, 7967.123616402076, 7536.472446928662, 7618.771436856903, 7692.884606730791, 7797.571133587707, 7889.960550197212, 7142.214356420685, 7190.388816950409, 7264.382527485223, 7268.636568171464, 7273.309035641606, 5117.641765076409, 5129.666571672829, 5134.178531434953, 5178.533931946143, 5204.115300399017, 5136.178211486144, 5175.534201921845, 5202.635815492879, 5299.947000529945, 5385.13837785968], "value": 4728.103758557495, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [250.9924702258932, 260.31926512170674, 313.2044606698985, 318.76570720520414, 326.16664422400754, 4501.549845015501, 4764.952350476494, 4979.751012449381, 5380.811671591495, 5471.139812463762, 4623.190941585226, 4951.876203094925, 5267.26258323834, 5422.566194704424, 5426.2674538937235, 3149.8740050397955, 3668.6881615062753, 3876.8061596920184, 3982.5021872265966, 4161.313383291753, 4094.1811637672436, 4119.382092686091, 4135.31767258402, 4150.315197992328, 4155.33514637658, 3602.126894015111, 4089.7750623715683, 4092.774897380643, 4122.454079720105, 4164.895877603054, 3922.9607703923025, 3980.263651224504, 3988.880333589997, 4091.672666186698, 4127.442795222667, 3563.0, 3565.94651080234, 3587.6142365327028, 3597.478365636968, 3622.3660859349507, 4317.97841010796, 4370.541093185201, 5134.845954621367, 5178.974105129488, 5199.402068762065, 4206.663466922639, 4523.864284071483, 4617.884552886171, 4745.576327101856, 4759.381280433556, 4727.574518293336, 5669.0, 5671.00757367459, 5684.8294551163535, 5687.886242275173, 3515.2969406118877, 3738.4018557030745, 3796.4305354196817, 3796.487474191004, 3996.760194388346, 5799.179511689932, 5836.649801011953, 5877.67672777997, 5975.790847320323, 6007.695767327952, 3548.5741710994844, 4010.8796736097966, 4040.8585699500377, 4049.6714389226313, 4052.3313653246882, 3470.6355832637714, 3650.452432135201, 4128.339465685476, 4137.627613514798, 4143.875683729493, 4558.766204559808, 4571.382863313476, 4672.929906051412, 4727.834525791581, 4750.61995040396, 4952.959878425499, 5650.463205995424, 5687.7156142192725, 5696.287964004499, 5724.77100915967, 1275.2412314672754, 1408.260663151833, 1410.3230449384353, 1410.6684929041667, 1414.4412956881974, 700.1563116444672, 794.606669698503, 847.8934989838197, 849.0278630967507, 850.503114518452, 4765.0, 5097.515736005073, 5180.280352814802, 5219.3736751589295, 5234.01661053196, 5605.0, 5872.0, 5880.411958804171, 5886.676232807151, 5987.191729116601, 4709.646776491777, 5096.700341412916, 5297.761600727977, 5299.350324837581, 5320.962412329636, 2648.860989774377, 2760.751532362097, 2939.4268117717265, 2946.9410611787644, 3069.907902762921, 4632.490426053131, 5511.338639363302, 5640.830775076754, 6066.48434883031, 6128.050152226412, 5202.0, 6025.578209525291, 6026.7287972040485, 6031.868170303356, 6043.184170137063, 5247.317848679723, 5291.3385826771655, 5846.736896839568, 5867.970660146757, 6094.542909281821, 5196.376434827845, 5441.619086663895, 5514.897020595977, 5548.612846788303, 5856.882862342644, 5113.8977220454635, 5731.942680573307, 5755.3021858552665, 5777.0, 5787.768489260382, 5126.769295381642, 5395.325584301962, 5811.302643682785, 6060.393960603904, 6130.908036379368, 3638.599754027108, 3932.525303011371, 4000.879973600797, 4010.1177740896956, 4019.3167161582014, 5304.708241046779, 5746.253746253747, 5816.331121921034, 5887.793927212527, 5911.172435858897, 4798.568128868418, 5700.714964251853, 5744.827655170351, 5753.683547404933, 5784.595078344475, 4845.709257444564, 5200.947990520198, 5289.518934698291, 5454.699991500505, 5745.683987380734, 4929.433115191799, 5524.8342549723575, 5537.640053396516, 5588.0, 5588.497035266845, 4899.118158731462, 5157.535821776059, 5367.731613419392, 5581.972090139523, 5590.804321848878, 4949.881574607974, 5222.712750798591, 5598.524125449219, 5663.490285874291, 5686.43135686428], "value": 4108.889368592866, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [724.4277021153288, 818.1695578987329, 821.7534739578125, 840.953747543885, 858.9656413743451, 14638.560843174708, 15323.387064517434, 15335.616609584766, 15341.309641066151, 15346.0, 13921.58235252944, 13965.88272938165, 13992.580222593313, 13993.370298336575, 13998.30008499576, 702.8629417263622, 727.3381223086992, 866.9783255418602, 890.6570970176497, 891.5854127830557, 875.3697337916722, 888.3914518554778, 891.162307431016, 891.8260939116875, 892.7366426904078, 945.2343601682659, 945.7021038372925, 945.9858102128474, 946.1768261612407, 949.7340744591526, 727.6616373386374, 914.4787471141452, 941.6139382853031, 945.0596656326999, 949.7815502434479, 701.5685353507556, 702.5995182745837, 702.8769965256059, 703.3704834173386, 703.7536862098276, 14497.56507304772, 14533.636659083502, 14542.70914581713, 14562.927185364006, 14564.927175364055, 11492.712682182928, 13122.2126672399, 13303.467861285633, 13450.461981520728, 13464.730705385935, 10598.364098154058, 11813.822792658117, 11968.34174120423, 11971.162018658697, 12593.559225427158, 4409.919330743956, 4411.382406463065, 4412.338149277602, 4413.0, 4534.523874993144, 80506.59746701288, 81178.59410702909, 82007.58996204981, 83052.92367690796, 83197.58401207955, 9970.551325190383, 10371.585136594602, 10758.354498730101, 10815.621453249256, 10954.671359859218, 20315.18739250443, 20380.184792608427, 20539.178432862816, 20564.074616642287, 20573.58852822965, 7681.155072941972, 7724.304812566784, 7769.601471735029, 7776.455648104578, 7883.921160788434, 4363.267881003627, 4383.408239887638, 4433.733975961452, 4453.710598163316, 4549.954500454953, 3454.4472884338384, 3542.4509201073874, 3544.025393016941, 3544.858205671796, 3547.6629720176543, 662.7481557008306, 685.6845850908538, 687.8864987277143, 688.6900894597451, 691.6818263598801, 614.7950017964839, 646.6023395611664, 659.5877576514679, 666.0, 670.168990451846, 8763.211310982042, 9034.593443295067, 9075.81848363029, 9172.6789562365, 9332.066793320613, 609.9481544068715, 625.2746813696091, 639.146739103293, 641.2273210780997, 650.5771248688296, 4259.488861336597, 4273.857886997495, 4289.463817022872, 4323.891902702458, 4402.669799765031, 82879.92800180044, 85550.86122846979, 85731.0, 92345.0, 97959.51020244995, 80196.5990170057, 80324.19675803283, 82114.3577128466, 82208.58895705602, 82263.0, 69990.95104062997, 70621.64689176521, 72214.27785722181, 73375.99999999894, 74258.51482970419, 9146.268298536233, 9523.619055237712, 9684.128428441474, 9725.854112188465, 9818.509074546102, 43948.681539553865, 44837.551624483334, 46138.538614613426, 46825.76587117043, 46842.53157468382, 63351.0, 64032.35967640264, 64076.679616603484, 64102.0, 64154.35845641484, 12472.625821225378, 12497.12520123619, 12676.28755204667, 12887.742245155232, 13467.12463689857, 11441.771164576829, 14177.14937103777, 14504.0, 14672.266386680836, 14756.262186890826, 10789.784204316027, 13300.854077960088, 13334.06469564553, 13546.661333466742, 13675.589732308046, 13336.733265334446, 13349.532766353519, 13701.109427887553, 13767.173969561858, 13777.0, 12816.231026138461, 12980.870191297967, 12981.091323607257, 13077.934610326887, 13477.32613369308, 10947.890521094687, 10987.83518247211, 11026.889731102587, 11103.944480277547, 11504.53981840717, 10795.514201861091, 11131.320480174829, 11233.831492527455, 11242.044426223863, 11258.31125331207], "value": 7316.590559251395, "unit": "score"}], "name": "dromaeo_css", "value": 7316.030056326378, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 106.0, 112.0, 112.0, 102.0, 121.0, 165.0, 165.0, 113.0, 112.0], "value": 122.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 119.0, 118.0, 167.0, 124.0, 116.0, 135.0, 117.0, 140.0, 123.0], "value": 131.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 182.0, 183.0, 183.0, 181.0, 220.0, 187.0, 185.0, 184.0], "value": 187.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 72.0, 58.0, 87.0, 58.0, 58.0, 64.0, 62.0, 82.0, 57.0], "value": 67.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 106.0, 121.0, 72.0, 77.0, 69.0, 97.0, 66.0, 66.0, 103.0], "value": 85.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 86.0, 89.0, 87.0, 90.0, 86.0, 91.0, 87.0, 90.0, 86.0], "value": 88.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 95.0, 95.0, 94.0, 93.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 141.0, 88.0, 85.0, 86.0, 85.0, 86.0, 85.0, 85.0, 85.0], "value": 95.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 55.0, 55.0, 56.0, 56.0, 56.0, 56.0, 55.0, 56.0], "value": 55.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 84.0, 72.0, 77.0, 72.0, 71.0, 70.0, 71.0, 68.0, 71.0], "value": 72.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 86.0, 86.0, 84.0, 87.0, 86.0, 88.0, 123.0, 88.0], "value": 90.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 139.0, 153.0, 142.0, 143.0, 145.0, 151.0, 134.0, 164.0, 161.0], "value": 147.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 223.0, 219.0, 169.0, 164.0, 163.0, 166.0, 166.0, 164.0, 163.0], "value": 176.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 67.0, 67.0, 66.0, 65.0, 68.0, 65.0, 66.0, 63.0], "value": 65.7, "unit": "score"}], "name": "kraken", "value": 1480.1000000000001, "alertThreshold": 2.0}]} 13:37:40 INFO - Return code: 0 13:37:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 13:37:40 ERROR - # TBPL SUCCESS # 13:37:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:37:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:37:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:37:40 INFO - 2016-05-31 13:37:40.987 screenresolution[1681:10662] starting screenresolution argv=screenresolution get 13:37:40 INFO - 2016-05-31 13:37:40.990 screenresolution[1681:10662] Display 0: 1600x1200x32@60 13:37:40 INFO - 2016-05-31 13:37:40.996 screenresolution[1682:10665] starting screenresolution argv=screenresolution list 13:37:40 INFO - Available Modes on Display 0 13:37:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:37:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:37:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:37:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:37:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:37:41 INFO - Intel Iris: 13:37:41 INFO - Chipset Model: Intel Iris 13:37:41 INFO - Type: GPU 13:37:41 INFO - Bus: Built-In 13:37:41 INFO - VRAM (Dynamic, Max): 1536 MB 13:37:41 INFO - Vendor: Intel (0x8086) 13:37:41 INFO - Device ID: 0x0a2e 13:37:41 INFO - Revision ID: 0x0009 13:37:41 INFO - Displays: 13:37:41 INFO - Display: 13:37:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:37:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:37:41 INFO - Display Serial Number: 1600x1200 60 13:37:41 INFO - Main Display: Yes 13:37:41 INFO - Mirror: Off 13:37:41 INFO - Online: Yes 13:37:41 INFO - Rotation: Supported 13:37:41 INFO - Return code: 0 13:37:41 INFO - Running post-action listener: _resource_record_post_action 13:37:41 INFO - ##### 13:37:41 INFO - ##### Finished run-tests step (success) 13:37:41 INFO - ##### 13:37:41 INFO - Running post-run listener: _resource_record_post_run 13:37:41 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 32615936; Write bytes: 698473472; Read time: 324; Write time: 1899 13:37:41 INFO - TinderboxPrint: CPU usage
53.5% 13:37:41 INFO - TinderboxPrint: I/O read bytes / time
32,615,936 / 324 13:37:41 INFO - TinderboxPrint: I/O write bytes / time
698,473,472 / 1,899 13:37:41 INFO - TinderboxPrint: CPU idle
2,254.7 (46.5%) 13:37:41 INFO - TinderboxPrint: CPU system
963.0 (19.9%) 13:37:41 INFO - TinderboxPrint: CPU user
1,631.8 (33.6%) 13:37:41 INFO - TinderboxPrint: Swap in / out
429,092,864 / 0 13:37:41 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 395860480; Write bytes: 389384192; Read time: 29637; Write time: 735 13:37:41 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 30817792; Write bytes: 292527104; Read time: 305; Write time: 1115 13:37:41 INFO - Running post-run listener: _upload_blobber_files 13:37:41 INFO - Blob upload gear active. 13:37:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:37:41 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 13:37:41 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', 'Larch', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:37:41 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 Larch -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:37:42 INFO - (blobuploader) - INFO - Open directory for files ... 13:37:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:37:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:37:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:37:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:37:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:37:43 INFO - (blobuploader) - INFO - Done attempting. 13:37:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:37:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:37:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:37:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:37:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:37:44 INFO - (blobuploader) - INFO - Done attempting. 13:37:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:37:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:37:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:37:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:37:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:37:47 INFO - (blobuploader) - INFO - Done attempting. 13:37:47 INFO - (blobuploader) - INFO - Iteration through files over. 13:37:47 INFO - Return code: 0 13:37:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:37:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:37:47 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/9457155d933e327861730c901a7997c807e9246b8d705d2fa13510423b3f48ac6f4697d7340ec38d45cb229a3822487f2ab40deac89dc0be0f4ee69b7f31d460", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/fdef9c00395ca511a72b74292d753947cff33cd8d1aeb22b147c59499df3526fada53b82170d0a40c0c3d8a6156a50429103ba985dd2a635c13eb80b526ef751", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/de0f20d2ad12e4842021e4426327dbbfaddeb9afc142610592fadddc9daf8c7e5935245b6281a5dd2d19aaf1afa37af3ba9af4ef7d77f9b7a463aa5622a8795d"} 13:37:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:37:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:37:47 INFO - Contents: 13:37:47 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/9457155d933e327861730c901a7997c807e9246b8d705d2fa13510423b3f48ac6f4697d7340ec38d45cb229a3822487f2ab40deac89dc0be0f4ee69b7f31d460", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/fdef9c00395ca511a72b74292d753947cff33cd8d1aeb22b147c59499df3526fada53b82170d0a40c0c3d8a6156a50429103ba985dd2a635c13eb80b526ef751", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/de0f20d2ad12e4842021e4426327dbbfaddeb9afc142610592fadddc9daf8c7e5935245b6281a5dd2d19aaf1afa37af3ba9af4ef7d77f9b7a463aa5622a8795d"} 13:37:47 INFO - Running post-run listener: copy_logs_to_upload_dir 13:37:47 INFO - Copying logs to upload dir... 13:37:47 INFO - mkdir: /builds/slave/test/build/upload/logs 13:37:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1296.530812 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 36 secs) (at 2016-05-31 13:37:47.102859) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-31 13:37:47.106025) ========= 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.1zJO8tpGU4/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.4opkTg6n29/Listeners TMPDIR=/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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/Larch/sha512/9457155d933e327861730c901a7997c807e9246b8d705d2fa13510423b3f48ac6f4697d7340ec38d45cb229a3822487f2ab40deac89dc0be0f4ee69b7f31d460", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/fdef9c00395ca511a72b74292d753947cff33cd8d1aeb22b147c59499df3526fada53b82170d0a40c0c3d8a6156a50429103ba985dd2a635c13eb80b526ef751", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/de0f20d2ad12e4842021e4426327dbbfaddeb9afc142610592fadddc9daf8c7e5935245b6281a5dd2d19aaf1afa37af3ba9af4ef7d77f9b7a463aa5622a8795d"} build_url:https://queue.taskcluster.net/v1/task/XqiMmXIUTEKe6Hf9uPpf5g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011217 build_url: 'https://queue.taskcluster.net/v1/task/XqiMmXIUTEKe6Hf9uPpf5g/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/9457155d933e327861730c901a7997c807e9246b8d705d2fa13510423b3f48ac6f4697d7340ec38d45cb229a3822487f2ab40deac89dc0be0f4ee69b7f31d460", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/fdef9c00395ca511a72b74292d753947cff33cd8d1aeb22b147c59499df3526fada53b82170d0a40c0c3d8a6156a50429103ba985dd2a635c13eb80b526ef751", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/de0f20d2ad12e4842021e4426327dbbfaddeb9afc142610592fadddc9daf8c7e5935245b6281a5dd2d19aaf1afa37af3ba9af4ef7d77f9b7a463aa5622a8795d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-31 13:37:47.141617) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 13:37:47.142326) ========= 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.1zJO8tpGU4/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.4opkTg6n29/Listeners TMPDIR=/var/folders/q8/7k0t867j72zfp6m_r1ll249m00000w/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.005253 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 13:37:47.194821) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-31 13:37:47.195546) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-31 13:37:47.196213) ========= ========= Total master_lag: 0.28 =========