builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0141 starttime: 1461116507.39 results: success (0) buildid: 20160419163752 builduid: 174eada632af4cf794b390a57d2a1342 revision: f60bd9567a058bf0d4d5b35f0d53a46eba0aa500 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-19 18:41:47.388729) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-19 18:41:47.389171) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-19 18:41:47.389444) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-19 18:41:47.409221) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 18:41:47.409831) ========= 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.ZA7AMP6JQL/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.6OivNVnza5/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-19 18:41:47-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.36M=0.001s 2016-04-19 18:41:47 (8.36 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.111892 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 18:41:47.539625) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 18:41:47.541679) ========= 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.ZA7AMP6JQL/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.6OivNVnza5/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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.020157 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 18:41:47.590869) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-19 18:41:47.591220) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev f60bd9567a058bf0d4d5b35f0d53a46eba0aa500 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev f60bd9567a058bf0d4d5b35f0d53a46eba0aa500 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZA7AMP6JQL/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.6OivNVnza5/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-19 18:41:47,666 truncating revision to first 12 chars 2016-04-19 18:41:47,666 Setting DEBUG logging. 2016-04-19 18:41:47,666 attempt 1/10 2016-04-19 18:41:47,666 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/f60bd9567a05?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-19 18:41:49,070 unpacking tar archive at: fx-team-f60bd9567a05/testing/mozharness/ program finished with exit code 0 elapsedTime=1.776084 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-19 18:41:49.383975) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-19 18:41:49.384280) ========= script_repo_revision: f60bd9567a058bf0d4d5b35f0d53a46eba0aa500 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-19 18:41:49.384682) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-19 18:41:49.384956) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-19 18:41:49.398309) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 23 mins, 56 secs) (at 2016-04-19 18:41:49.398694) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZA7AMP6JQL/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.6OivNVnza5/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 18:41:49 INFO - MultiFileLogger online at 20160419 18:41:49 in /builds/slave/test 18:41:49 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 18:41:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:41:49 INFO - {'append_to_log': False, 18:41:49 INFO - 'base_work_dir': '/builds/slave/test', 18:41:49 INFO - 'blob_upload_branch': 'Fx-Team', 18:41:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:41:49 INFO - 'branch': 'Fx-Team', 18:41:49 INFO - 'buildbot_json_path': 'buildprops.json', 18:41:49 INFO - 'config_files': ('talos/mac_config.py',), 18:41:49 INFO - 'default_actions': ('clobber', 18:41:49 INFO - 'read-buildbot-config', 18:41:49 INFO - 'download-and-extract', 18:41:49 INFO - 'populate-webroot', 18:41:49 INFO - 'create-virtualenv', 18:41:49 INFO - 'install', 18:41:49 INFO - 'run-tests'), 18:41:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:41:49 INFO - 'download_minidump_stackwalk': True, 18:41:49 INFO - 'download_symbols': 'ondemand', 18:41:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:41:49 INFO - 'tooltool.py': '/tools/tooltool.py', 18:41:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:41:49 INFO - '/tools/misc-python/virtualenv.py')}, 18:41:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:41:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:41:49 INFO - 'installer_path': 'installer.exe', 18:41:49 INFO - 'log_level': 'info', 18:41:49 INFO - 'log_name': 'talos', 18:41:49 INFO - 'log_to_console': True, 18:41:49 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:41:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:41:49 INFO - 'opt_config_files': (), 18:41:49 INFO - 'pip_index': False, 18:41:49 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:41:49 INFO - 'cmd': ('bash', 18:41:49 INFO - '-c', 18:41:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:41:49 INFO - 'enabled': True, 18:41:49 INFO - 'halt_on_failure': False, 18:41:49 INFO - 'name': 'check_screen_resolution'},), 18:41:49 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:41:49 INFO - 'cmd': ('bash', 18:41:49 INFO - '-c', 18:41:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:41:49 INFO - 'enabled': True, 18:41:49 INFO - 'halt_on_failure': False, 18:41:49 INFO - 'name': 'check_screen_resolution'},), 18:41:49 INFO - 'run_cmd_checks_enabled': True, 18:41:49 INFO - 'sps_profile': False, 18:41:49 INFO - 'sps_profile_interval': 0, 18:41:49 INFO - 'suite': 'dromaeojs', 18:41:49 INFO - 'system_bits': '32', 18:41:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:41:49 INFO - 'title': 't-yosemite-r7-0141', 18:41:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:41:49 INFO - 'use_talos_json': True, 18:41:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:41:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:41:49 INFO - 'work_dir': 'build'} 18:41:49 INFO - ##### 18:41:49 INFO - ##### Running clobber step. 18:41:49 INFO - ##### 18:41:49 INFO - Running pre-action listener: _resource_record_pre_action 18:41:49 INFO - Running main action method: clobber 18:41:49 INFO - rmtree: /builds/slave/test/build 18:41:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:41:51 INFO - Running post-action listener: _resource_record_post_action 18:41:51 INFO - ##### 18:41:51 INFO - ##### Running read-buildbot-config step. 18:41:51 INFO - ##### 18:41:51 INFO - Running pre-action listener: _resource_record_pre_action 18:41:51 INFO - Running main action method: read_buildbot_config 18:41:51 INFO - Using buildbot properties: 18:41:51 INFO - { 18:41:51 INFO - "project": "", 18:41:51 INFO - "product": "firefox", 18:41:51 INFO - "script_repo_revision": "production", 18:41:51 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 18:41:51 INFO - "repository": "", 18:41:51 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 18:41:51 INFO - "buildid": "20160419163752", 18:41:51 INFO - "pgo_build": "False", 18:41:51 INFO - "basedir": "/builds/slave/test", 18:41:51 INFO - "buildnumber": 644, 18:41:51 INFO - "slavename": "t-yosemite-r7-0141", 18:41:51 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 18:41:51 INFO - "platform": "yosemite_r7", 18:41:51 INFO - "scheduler": "tests-fx-team-macosx64-talos", 18:41:51 INFO - "branch": "fx-team", 18:41:51 INFO - "repo_path": "integration/fx-team", 18:41:51 INFO - "revision": "f60bd9567a058bf0d4d5b35f0d53a46eba0aa500", 18:41:51 INFO - "stage_platform": "macosx64", 18:41:51 INFO - "builduid": "174eada632af4cf794b390a57d2a1342", 18:41:51 INFO - "slavebuilddir": "test" 18:41:51 INFO - } 18:41:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/WRjFB5zlT6iONjM4i02uLQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 18:41:51 INFO - Running post-action listener: _resource_record_post_action 18:41:51 INFO - ##### 18:41:51 INFO - ##### Running download-and-extract step. 18:41:51 INFO - ##### 18:41:51 INFO - Running pre-action listener: _resource_record_pre_action 18:41:51 INFO - Running main action method: download_and_extract 18:41:51 INFO - mkdir: /builds/slave/test/build/tests 18:41:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:41:51 INFO - https://queue.taskcluster.net/v1/task/WRjFB5zlT6iONjM4i02uLQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 18:41:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WRjFB5zlT6iONjM4i02uLQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 18:41:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WRjFB5zlT6iONjM4i02uLQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 18:41:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WRjFB5zlT6iONjM4i02uLQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 18:41:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WRjFB5zlT6iONjM4i02uLQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 18:41:52 INFO - Downloaded 1199 bytes. 18:41:52 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 18:41:52 INFO - Using the following test package requirements: 18:41:52 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 18:41:52 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 18:41:52 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 18:41:52 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 18:41:52 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 18:41:52 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 18:41:52 INFO - u'jsshell-mac.zip'], 18:41:52 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 18:41:52 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 18:41:52 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 18:41:52 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 18:41:52 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 18:41:52 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 18:41:52 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 18:41:52 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 18:41:52 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 18:41:52 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 18:41:52 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 18:41:52 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:41:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:41:52 INFO - https://queue.taskcluster.net/v1/task/WRjFB5zlT6iONjM4i02uLQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:41:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WRjFB5zlT6iONjM4i02uLQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 18:41:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WRjFB5zlT6iONjM4i02uLQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 18:41:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WRjFB5zlT6iONjM4i02uLQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 18:41:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WRjFB5zlT6iONjM4i02uLQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:41:54 INFO - Downloaded 18095573 bytes. 18:41:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:41:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 18:41:57 INFO - Return code: 0 18:41:57 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 18:41: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')]} 18:41:57 INFO - https://queue.taskcluster.net/v1/task/WRjFB5zlT6iONjM4i02uLQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:41:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WRjFB5zlT6iONjM4i02uLQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 18:41:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WRjFB5zlT6iONjM4i02uLQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 18:41:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WRjFB5zlT6iONjM4i02uLQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 18:41:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WRjFB5zlT6iONjM4i02uLQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:41:59 INFO - Downloaded 18095573 bytes. 18:41:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:41:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 18:42:02 INFO - Return code: 0 18:42: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')]} 18:42:02 INFO - https://queue.taskcluster.net/v1/task/WRjFB5zlT6iONjM4i02uLQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 18:42:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WRjFB5zlT6iONjM4i02uLQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 18:42:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WRjFB5zlT6iONjM4i02uLQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 18:42:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WRjFB5zlT6iONjM4i02uLQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 18:42:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WRjFB5zlT6iONjM4i02uLQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:42:04 INFO - Downloaded 10845952 bytes. 18:42:04 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:42:04 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 18:42:04 INFO - Return code: 0 18:42:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:42:04 INFO - https://queue.taskcluster.net/v1/task/WRjFB5zlT6iONjM4i02uLQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 18:42:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WRjFB5zlT6iONjM4i02uLQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 18:42:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WRjFB5zlT6iONjM4i02uLQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 18:42:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WRjFB5zlT6iONjM4i02uLQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 18:42:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WRjFB5zlT6iONjM4i02uLQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 18:42:07 INFO - Downloaded 109985449 bytes. 18:42:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WRjFB5zlT6iONjM4i02uLQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 18:42:07 INFO - mkdir: /builds/slave/test/properties 18:42:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:42:07 INFO - Writing to file /builds/slave/test/properties/build_url 18:42:07 INFO - Contents: 18:42:07 INFO - build_url:https://queue.taskcluster.net/v1/task/WRjFB5zlT6iONjM4i02uLQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 18:42:08 INFO - Running post-action listener: _resource_record_post_action 18:42:08 INFO - Running post-action listener: set_extra_try_arguments 18:42:08 INFO - ##### 18:42:08 INFO - ##### Running populate-webroot step. 18:42:08 INFO - ##### 18:42:08 INFO - Running pre-action listener: _resource_record_pre_action 18:42:08 INFO - Running main action method: populate_webroot 18:42:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:42:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:42:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:42:08 INFO - '--tppagecycles', 18:42:08 INFO - '7'], 18:42:08 INFO - 'tests': ['tsvgm']}}, 18:42:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:42:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:42:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:42:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:42:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:42:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:42:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:42:08 INFO - 'talos_options': ['--disable-e10s'], 18:42:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:42:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:42:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:42:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:42:08 INFO - 'talos_options': ['--disable-e10s'], 18:42:08 INFO - 'tests': ['damp', 'tps']}, 18:42:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:42:08 INFO - 'tests': ['damp', 'tps']}, 18:42:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:42:08 INFO - 'tests': ['dromaeo_dom']}, 18:42:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:42:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:42:08 INFO - 'tests': ['a11yr', 18:42:08 INFO - 'ts_paint', 18:42:08 INFO - 'tpaint', 18:42:08 INFO - 'sessionrestore', 18:42:08 INFO - 'sessionrestore_no_auto_restore', 18:42:08 INFO - 'tabpaint']}, 18:42:08 INFO - 'other-e10s': {'tests': ['a11yr', 18:42:08 INFO - 'ts_paint', 18:42:08 INFO - 'tpaint', 18:42:08 INFO - 'sessionrestore', 18:42:08 INFO - 'sessionrestore_no_auto_restore', 18:42:08 INFO - 'tabpaint']}, 18:42:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:42:08 INFO - 'ts_paint', 18:42:08 INFO - 'tpaint', 18:42:08 INFO - 'sessionrestore', 18:42:08 INFO - 'sessionrestore_no_auto_restore', 18:42:08 INFO - 'tabpaint']}, 18:42:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:42:08 INFO - 'ts_paint', 18:42:08 INFO - 'tpaint', 18:42:08 INFO - 'sessionrestore', 18:42:08 INFO - 'sessionrestore_no_auto_restore', 18:42:08 INFO - 'tabpaint']}, 18:42:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:42:08 INFO - 'tests': ['a11yr', 18:42:08 INFO - 'ts_paint', 18:42:08 INFO - 'tpaint', 18:42:08 INFO - 'sessionrestore', 18:42:08 INFO - 'sessionrestore_no_auto_restore', 18:42:08 INFO - 'tabpaint']}, 18:42:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:42:08 INFO - 'tests': ['a11yr', 18:42:08 INFO - 'ts_paint', 18:42:08 INFO - 'tpaint', 18:42:08 INFO - 'sessionrestore', 18:42:08 INFO - 'sessionrestore_no_auto_restore', 18:42:08 INFO - 'tabpaint']}, 18:42:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:42:08 INFO - 'tests': ['tsvgx', 18:42:08 INFO - 'tsvgr_opacity', 18:42:08 INFO - 'tart', 18:42:08 INFO - 'tscrollx', 18:42:08 INFO - 'cart']}, 18:42:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:42:08 INFO - 'tsvgr_opacity', 18:42:08 INFO - 'tart', 18:42:08 INFO - 'tscrollx', 18:42:08 INFO - 'cart']}, 18:42:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:42:08 INFO - 'talos_options': ['--disable-e10s'], 18:42:08 INFO - 'tests': ['tp5o']}, 18:42:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:42:08 INFO - 'tests': ['tp5o']}, 18:42:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:42:08 INFO - 'talos_options': ['--disable-e10s', 18:42:08 INFO - '--xperf_path', 18:42:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:42:08 INFO - 'tests': ['tp5n']}, 18:42:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:42:08 INFO - 'talos_options': ['--xperf_path', 18:42:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:42:08 INFO - 'tests': ['tp5n']}}, 18:42:08 INFO - 'talos.zip': {'path': '', 18:42:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:42:08 INFO - Running post-action listener: _resource_record_post_action 18:42:08 INFO - ##### 18:42:08 INFO - ##### Running create-virtualenv step. 18:42:08 INFO - ##### 18:42:08 INFO - Running pre-action listener: _resource_record_pre_action 18:42:08 INFO - Running main action method: create_virtualenv 18:42:08 INFO - Creating virtualenv /builds/slave/test/build/venv 18:42:08 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 18:42:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:42:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:42:08 INFO - Using real prefix '/tools/python27' 18:42:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:42:09 INFO - Installing distribute.............................................................................................................................................................................................done. 18:42:12 INFO - Installing pip.................done. 18:42:12 INFO - Return code: 0 18:42:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:42: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')]} 18:42:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42: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')]} 18:42:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:12 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 0x10f92fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f93f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90a3496960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7cee00>, '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 0x10f5f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7c9b20>, '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.ZA7AMP6JQL/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.6OivNVnza5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 18:42:12 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 18:42:12 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 18:42:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZA7AMP6JQL/Render', 18:42:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:42:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:42:12 INFO - 'HOME': '/Users/cltbld', 18:42:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:42:12 INFO - 'LOGNAME': 'cltbld', 18:42:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:12 INFO - 'MOZ_NO_REMOTE': '1', 18:42:12 INFO - 'NO_EM_RESTART': '1', 18:42:12 INFO - 'PAGER': '/bin/cat', 18:42:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:42:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:12 INFO - 'PWD': '/builds/slave/test', 18:42:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:42:12 INFO - 'SHELL': '/bin/bash', 18:42:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6OivNVnza5/Listeners', 18:42:12 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 18:42:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:42:12 INFO - 'USER': 'cltbld', 18:42:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:42:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:42:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:12 INFO - 'XPC_FLAGS': '0x0', 18:42:12 INFO - 'XPC_SERVICE_NAME': '0', 18:42:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:42:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:12 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 18:42:12 INFO - Cleaning up... 18:42:12 INFO - Return code: 0 18:42:12 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 18:42: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')]} 18:42:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42: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')]} 18:42:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f92fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f93f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90a3496960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7cee00>, '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 0x10f5f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7c9b20>, '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.ZA7AMP6JQL/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.6OivNVnza5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 18:42:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 18:42:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 18:42:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZA7AMP6JQL/Render', 18:42:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:42:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:42:12 INFO - 'HOME': '/Users/cltbld', 18:42:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:42:12 INFO - 'LOGNAME': 'cltbld', 18:42:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:12 INFO - 'MOZ_NO_REMOTE': '1', 18:42:12 INFO - 'NO_EM_RESTART': '1', 18:42:12 INFO - 'PAGER': '/bin/cat', 18:42:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:42:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:12 INFO - 'PWD': '/builds/slave/test', 18:42:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:42:12 INFO - 'SHELL': '/bin/bash', 18:42:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6OivNVnza5/Listeners', 18:42:12 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 18:42:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:42:12 INFO - 'USER': 'cltbld', 18:42:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:42:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:42:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:12 INFO - 'XPC_FLAGS': '0x0', 18:42:12 INFO - 'XPC_SERVICE_NAME': '0', 18:42:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:42:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:12 INFO - Downloading/unpacking psutil>=0.7.1 18:42:12 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 18:42:12 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 18:42:12 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 18:42:12 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 18:42:12 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 18:42:12 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 18:42:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:42:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:42:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:42:16 INFO - Installing collected packages: psutil 18:42:16 INFO - Running setup.py install for psutil 18:42:16 INFO - building 'psutil._psutil_osx' extension 18:42:16 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 18:42:16 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 18:42:17 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 18:42:17 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 18:42:17 INFO - building 'psutil._psutil_posix' extension 18:42:17 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 18:42:17 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:42:17 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:42:17 INFO - ^ 18:42:17 INFO - 1 warning generated. 18:42:17 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 18:42:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:42:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:42:17 INFO - Successfully installed psutil 18:42:17 INFO - Cleaning up... 18:42:17 INFO - Return code: 0 18:42:17 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 18:42:17 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')]} 18:42:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:17 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')]} 18:42:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f92fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f93f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90a3496960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7cee00>, '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 0x10f5f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7c9b20>, '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.ZA7AMP6JQL/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.6OivNVnza5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 18:42:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 18:42:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 18:42:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZA7AMP6JQL/Render', 18:42:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:42:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:42:17 INFO - 'HOME': '/Users/cltbld', 18:42:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:42:17 INFO - 'LOGNAME': 'cltbld', 18:42:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:17 INFO - 'MOZ_NO_REMOTE': '1', 18:42:17 INFO - 'NO_EM_RESTART': '1', 18:42:17 INFO - 'PAGER': '/bin/cat', 18:42:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:42:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:17 INFO - 'PWD': '/builds/slave/test', 18:42:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:42:17 INFO - 'SHELL': '/bin/bash', 18:42:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6OivNVnza5/Listeners', 18:42:17 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 18:42:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:42:17 INFO - 'USER': 'cltbld', 18:42:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:42:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:42:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:17 INFO - 'XPC_FLAGS': '0x0', 18:42:17 INFO - 'XPC_SERVICE_NAME': '0', 18:42:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:42:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:17 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:42:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:42: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 18:42:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:42:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:42:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:42: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 18:42:20 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:42:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:42:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 18:42:20 INFO - Installing collected packages: mozsystemmonitor 18:42:20 INFO - Running setup.py install for mozsystemmonitor 18:42:20 INFO - Successfully installed mozsystemmonitor 18:42:20 INFO - Cleaning up... 18:42:20 INFO - Return code: 0 18:42:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:42: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')]} 18:42:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42: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')]} 18:42:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42: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', '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 0x10f92fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f93f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90a3496960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7cee00>, '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 0x10f5f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7c9b20>, '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.ZA7AMP6JQL/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.6OivNVnza5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 18:42: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:42: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 blobuploader==1.2.4 18:42:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZA7AMP6JQL/Render', 18:42:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:42:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:42:20 INFO - 'HOME': '/Users/cltbld', 18:42:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:42:20 INFO - 'LOGNAME': 'cltbld', 18:42:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:20 INFO - 'MOZ_NO_REMOTE': '1', 18:42:20 INFO - 'NO_EM_RESTART': '1', 18:42:20 INFO - 'PAGER': '/bin/cat', 18:42:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:42:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:20 INFO - 'PWD': '/builds/slave/test', 18:42:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:42:20 INFO - 'SHELL': '/bin/bash', 18:42:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6OivNVnza5/Listeners', 18:42:20 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 18:42:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:42:20 INFO - 'USER': 'cltbld', 18:42:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:42:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:42:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:20 INFO - 'XPC_FLAGS': '0x0', 18:42:20 INFO - 'XPC_SERVICE_NAME': '0', 18:42:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:42:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:20 INFO - Downloading/unpacking blobuploader==1.2.4 18:42: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 18:42: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 18:42: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 18:42: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 18:42: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 18:42: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 18:42:23 INFO - Downloading blobuploader-1.2.4.tar.gz 18:42:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:42:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:42: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 18:42: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 18:42: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 18:42: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 18:42: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 18:42: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 18:42:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:42:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:42: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 18:42: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 18:42: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 18:42: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 18:42: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 18:42: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 18:42:24 INFO - Downloading docopt-0.6.1.tar.gz 18:42:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:42:24 INFO - Installing collected packages: blobuploader, requests, docopt 18:42:24 INFO - Running setup.py install for blobuploader 18:42:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:42:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:42:24 INFO - Running setup.py install for requests 18:42:25 INFO - Running setup.py install for docopt 18:42:25 INFO - Successfully installed blobuploader requests docopt 18:42:25 INFO - Cleaning up... 18:42:25 INFO - Return code: 0 18:42:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:42:25 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')]} 18:42:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:25 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')]} 18:42:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:25 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 0x10f92fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f93f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90a3496960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7cee00>, '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 0x10f5f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7c9b20>, '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.ZA7AMP6JQL/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.6OivNVnza5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 18:42:25 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 18:42:25 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 18:42:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZA7AMP6JQL/Render', 18:42:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:42:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:42:25 INFO - 'HOME': '/Users/cltbld', 18:42:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:42:25 INFO - 'LOGNAME': 'cltbld', 18:42:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:25 INFO - 'MOZ_NO_REMOTE': '1', 18:42:25 INFO - 'NO_EM_RESTART': '1', 18:42:25 INFO - 'PAGER': '/bin/cat', 18:42:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:42:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:25 INFO - 'PWD': '/builds/slave/test', 18:42:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:42:25 INFO - 'SHELL': '/bin/bash', 18:42:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6OivNVnza5/Listeners', 18:42:25 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 18:42:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:42:25 INFO - 'USER': 'cltbld', 18:42:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:42:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:42:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:25 INFO - 'XPC_FLAGS': '0x0', 18:42:25 INFO - 'XPC_SERVICE_NAME': '0', 18:42:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:42:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:42:25 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-b_Wm2_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:42:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:42:25 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-PE9Bh0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:42:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:42:25 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-OU1KrG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:42:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:42:25 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-n6YSMq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:42:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:42:26 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-GlRfoZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:42:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:42:26 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-wqmuyq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:42:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:42:26 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-lI7gKX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:42:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:42:26 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-IVIQKH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:42:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:42:26 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-Qj21vc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:42:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:42:26 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-XGrX1z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:42:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:42:26 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-SC93ds-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:42:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:42:26 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-fQO8ij-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:42:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:42:26 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-jKeugR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:42:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:42:26 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-WCTeZR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:42:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:42:27 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-hNVHpY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:42:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:42:27 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-8h6emr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:42:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:42:27 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-xe0twC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:42:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:42:27 INFO - Running setup.py install for manifestparser 18:42:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:42:27 INFO - Running setup.py install for mozcrash 18:42:27 INFO - Running setup.py install for mozdebug 18:42:27 INFO - Running setup.py install for mozdevice 18:42:27 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:42:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:42:27 INFO - Running setup.py install for mozfile 18:42:28 INFO - Running setup.py install for mozhttpd 18:42:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:42:28 INFO - Running setup.py install for mozinfo 18:42:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:42:28 INFO - Running setup.py install for mozInstall 18:42:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:42:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:42:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:42:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:42:28 INFO - Running setup.py install for mozleak 18:42:28 INFO - Running setup.py install for mozlog 18:42:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:42:28 INFO - Running setup.py install for moznetwork 18:42:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:42:28 INFO - Running setup.py install for mozprocess 18:42:29 INFO - Running setup.py install for mozprofile 18:42:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:42:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:42:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:42:29 INFO - Running setup.py install for mozrunner 18:42:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:42:29 INFO - Running setup.py install for mozscreenshot 18:42:29 INFO - Running setup.py install for moztest 18:42:29 INFO - Running setup.py install for mozversion 18:42:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:42:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:42:29 INFO - Cleaning up... 18:42:29 INFO - Return code: 0 18:42:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:42:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:42:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:42:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:29 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 0x10f92fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f93f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90a3496960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7cee00>, '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 0x10f5f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7c9b20>, '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.ZA7AMP6JQL/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.6OivNVnza5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 18:42:29 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 18:42:29 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 18:42:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZA7AMP6JQL/Render', 18:42:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:42:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:42:29 INFO - 'HOME': '/Users/cltbld', 18:42:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:42:29 INFO - 'LOGNAME': 'cltbld', 18:42:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:29 INFO - 'MOZ_NO_REMOTE': '1', 18:42:29 INFO - 'NO_EM_RESTART': '1', 18:42:29 INFO - 'PAGER': '/bin/cat', 18:42:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:42:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:29 INFO - 'PWD': '/builds/slave/test', 18:42:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:42:29 INFO - 'SHELL': '/bin/bash', 18:42:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6OivNVnza5/Listeners', 18:42:29 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 18:42:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:42:29 INFO - 'USER': 'cltbld', 18:42:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:42:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:42:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:29 INFO - 'XPC_FLAGS': '0x0', 18:42:29 INFO - 'XPC_SERVICE_NAME': '0', 18:42:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:42:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:42:30 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-xHqgGg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:42:30 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)) 18:42:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:42:30 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-kLi5A9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:42:30 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)) 18:42:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:42:30 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-iCu0FJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:42:30 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)) 18:42:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:42:30 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-ISzYNT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:42:30 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)) 18:42:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:42:30 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-rm1XHp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:42:30 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)) 18:42:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:42:30 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-MxhfGS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:42:30 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)) 18:42:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:42:30 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-Gqn0HE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:42:30 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)) 18:42:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:42:30 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-i_zndX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:42:31 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)) 18:42:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:42:31 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-7_YHfT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:42:31 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)) 18:42:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:42:31 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-5geNJ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:42:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:42:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:42:31 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-ZgKCgx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:42:31 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)) 18:42:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:42:31 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-riAGnU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:42:31 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)) 18:42:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:42:31 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-gSPIxx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:42:31 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)) 18:42:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:42:31 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-KZl0DT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:42:31 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)) 18:42:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:42:31 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-SBLcD2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:42:31 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)) 18:42:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:42:31 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-FEoP91-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:42:31 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)) 18:42:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:42:31 INFO - Running setup.py (path:/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/pip-cgM3iM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:42:31 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)) 18:42:31 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)) 18:42:31 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)) 18:42:31 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)) 18:42:31 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)) 18:42:31 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)) 18:42:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:42:31 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 18:42:31 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 18:42:31 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 18:42:31 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 18:42:31 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 18:42:31 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 18:42:34 INFO - Downloading blessings-1.6.tar.gz 18:42:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:42:34 INFO - Installing collected packages: blessings 18:42:34 INFO - Running setup.py install for blessings 18:42:34 INFO - Successfully installed blessings 18:42:34 INFO - Cleaning up... 18:42:34 INFO - Return code: 0 18:42:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:42:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:42:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:42:35 INFO - Reading from file tmpfile_stdout 18:42:35 INFO - Current package versions: 18:42:35 INFO - blessings == 1.6 18:42:35 INFO - blobuploader == 1.2.4 18:42:35 INFO - docopt == 0.6.1 18:42:35 INFO - manifestparser == 1.1 18:42:35 INFO - mozInstall == 1.12 18:42:35 INFO - mozcrash == 0.17 18:42:35 INFO - mozdebug == 0.1 18:42:35 INFO - mozdevice == 0.48 18:42:35 INFO - mozfile == 1.2 18:42:35 INFO - mozhttpd == 0.7 18:42:35 INFO - mozinfo == 0.9 18:42:35 INFO - mozleak == 0.1 18:42:35 INFO - mozlog == 3.1 18:42:35 INFO - moznetwork == 0.27 18:42:35 INFO - mozprocess == 0.22 18:42:35 INFO - mozprofile == 0.28 18:42:35 INFO - mozrunner == 6.11 18:42:35 INFO - mozscreenshot == 0.1 18:42:35 INFO - mozsystemmonitor == 0.0 18:42:35 INFO - moztest == 0.7 18:42:35 INFO - mozversion == 1.4 18:42:35 INFO - psutil == 3.1.1 18:42:35 INFO - requests == 1.2.3 18:42:35 INFO - wsgiref == 0.1.2 18:42:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:42:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:42:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:42:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:35 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 0x10f92fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f93f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90a3496960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7cee00>, '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 0x10f5f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7c9b20>, '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.ZA7AMP6JQL/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.6OivNVnza5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 18:42:35 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 18:42:35 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 18:42:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZA7AMP6JQL/Render', 18:42:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:42:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:42:35 INFO - 'HOME': '/Users/cltbld', 18:42:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:42:35 INFO - 'LOGNAME': 'cltbld', 18:42:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:35 INFO - 'MOZ_NO_REMOTE': '1', 18:42:35 INFO - 'NO_EM_RESTART': '1', 18:42:35 INFO - 'PAGER': '/bin/cat', 18:42:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:42:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:35 INFO - 'PWD': '/builds/slave/test', 18:42:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:42:35 INFO - 'SHELL': '/bin/bash', 18:42:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6OivNVnza5/Listeners', 18:42:35 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 18:42:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:42:35 INFO - 'USER': 'cltbld', 18:42:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:42:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:42:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:35 INFO - 'XPC_FLAGS': '0x0', 18:42:35 INFO - 'XPC_SERVICE_NAME': '0', 18:42:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:42:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:35 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)) 18:42:35 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)) 18:42:35 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)) 18:42:35 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)) 18:42:35 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)) 18:42:35 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)) 18:42:35 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)) 18:42:35 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)) 18:42:35 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)) 18:42:35 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)) 18:42:35 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)) 18:42:35 INFO - Cleaning up... 18:42:35 INFO - Return code: 0 18:42:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:42:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:42:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:42:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:35 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 0x10f92fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f93f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90a3496960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7cee00>, '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 0x10f5f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7c9b20>, '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.ZA7AMP6JQL/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.6OivNVnza5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 18:42:35 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 18:42:35 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 18:42:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZA7AMP6JQL/Render', 18:42:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:42:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:42:35 INFO - 'HOME': '/Users/cltbld', 18:42:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:42:35 INFO - 'LOGNAME': 'cltbld', 18:42:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:35 INFO - 'MOZ_NO_REMOTE': '1', 18:42:35 INFO - 'NO_EM_RESTART': '1', 18:42:35 INFO - 'PAGER': '/bin/cat', 18:42:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:42:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:35 INFO - 'PWD': '/builds/slave/test', 18:42:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:42:35 INFO - 'SHELL': '/bin/bash', 18:42:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6OivNVnza5/Listeners', 18:42:35 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 18:42:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:42:35 INFO - 'USER': 'cltbld', 18:42:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:42:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:42:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:35 INFO - 'XPC_FLAGS': '0x0', 18:42:35 INFO - 'XPC_SERVICE_NAME': '0', 18:42:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:42:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:35 INFO - Downloading/unpacking jsonschema 18:42:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:42:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:42:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:42:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:42:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:42:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:42:37 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:42:37 INFO - Downloading/unpacking functools32 (from jsonschema) 18:42:37 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 18:42:37 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 18:42:37 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 18:42:37 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 18:42:37 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 18:42:37 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 18:42:38 INFO - Downloading functools32-3.2.3-2.tar.gz 18:42:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:42:38 INFO - warning: no files found matching '*.txt' 18:42:38 INFO - no previously-included directories found matching 'build' 18:42:38 INFO - no previously-included directories found matching 'dist' 18:42:38 INFO - no previously-included directories found matching '.git*' 18:42:38 INFO - Installing collected packages: jsonschema, functools32 18:42:38 INFO - Running setup.py install for functools32 18:42:38 INFO - warning: no files found matching '*.txt' 18:42:38 INFO - no previously-included directories found matching 'build' 18:42:38 INFO - no previously-included directories found matching 'dist' 18:42:38 INFO - no previously-included directories found matching '.git*' 18:42:38 INFO - Successfully installed jsonschema functools32 18:42:38 INFO - Cleaning up... 18:42:38 INFO - Return code: 0 18:42:38 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 18:42: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')]} 18:42:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:42:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42: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')]} 18:42:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:42:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:42:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f92fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f93f420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90a3496960>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7cee00>, '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 0x10f5f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7c9b20>, '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.ZA7AMP6JQL/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.6OivNVnza5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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 18:42:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 18:42:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 18:42:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZA7AMP6JQL/Render', 18:42:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:42:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:42:38 INFO - 'HOME': '/Users/cltbld', 18:42:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:42:38 INFO - 'LOGNAME': 'cltbld', 18:42:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:42:38 INFO - 'MOZ_NO_REMOTE': '1', 18:42:38 INFO - 'NO_EM_RESTART': '1', 18:42:38 INFO - 'PAGER': '/bin/cat', 18:42:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:42:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:42:38 INFO - 'PWD': '/builds/slave/test', 18:42:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:42:38 INFO - 'SHELL': '/bin/bash', 18:42:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6OivNVnza5/Listeners', 18:42:38 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 18:42:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:42:38 INFO - 'USER': 'cltbld', 18:42:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:42:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:42:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:42:38 INFO - 'XPC_FLAGS': '0x0', 18:42:38 INFO - 'XPC_SERVICE_NAME': '0', 18:42:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:42:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:42:38 INFO - Downloading/unpacking flake8 18:42:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:42:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:42:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:42:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:42:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:42:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:42:41 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:42:41 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:42:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:42:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:42:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:42:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:42:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:42:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:42:41 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:42:41 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:42:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:42:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:42:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:42:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:42:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:42:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:42:42 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:42:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:42:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:42:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:42:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:42:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:42:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:42:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 18:42:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:42:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:42:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:42:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:42:42 INFO - no previously-included directories found matching 'docs/_build' 18:42:42 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:42:42 INFO - Running setup.py install for pep8 18:42:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:42:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:42:42 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:42:42 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:42:42 INFO - no previously-included directories found matching 'docs/_build' 18:42:43 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 18:42:43 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:42:43 INFO - Cleaning up... 18:42:43 INFO - Return code: 0 18:42:43 INFO - Running post-action listener: _resource_record_post_action 18:42:43 INFO - Running post-action listener: _start_resource_monitoring 18:42:43 INFO - Starting resource monitoring. 18:42:43 INFO - ##### 18:42:43 INFO - ##### Running install step. 18:42:43 INFO - ##### 18:42:43 INFO - Running pre-action listener: _resource_record_pre_action 18:42:43 INFO - Running main action method: install 18:42:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:42:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:42:43 INFO - Reading from file tmpfile_stdout 18:42:43 INFO - Detecting whether we're running mozinstall >=1.0... 18:42:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:42:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:42:43 INFO - Reading from file tmpfile_stdout 18:42:43 INFO - Output received: 18:42:43 INFO - Usage: mozinstall [options] installer 18:42:43 INFO - Options: 18:42:43 INFO - -h, --help show this help message and exit 18:42:43 INFO - -d DEST, --destination=DEST 18:42:43 INFO - Directory to install application into. [default: 18:42:43 INFO - "/builds/slave/test"] 18:42:43 INFO - --app=APP Application being installed. [default: firefox] 18:42:43 INFO - mkdir: /builds/slave/test/build/application 18:42:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:42:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 18:43:17 INFO - Reading from file tmpfile_stdout 18:43:17 INFO - Output received: 18:43:17 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:43:17 INFO - Running post-action listener: _resource_record_post_action 18:43:17 INFO - ##### 18:43:17 INFO - ##### Running run-tests step. 18:43:17 INFO - ##### 18:43:17 INFO - Running pre-action listener: _resource_record_pre_action 18:43:17 INFO - Running main action method: run_tests 18:43:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:43:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:43:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:43:17 INFO - 2016-04-19 18:43:17.884 screenresolution[2189:16480] starting screenresolution argv=screenresolution get 18:43:17 INFO - 2016-04-19 18:43:17.887 screenresolution[2189:16480] Display 0: 1600x1200x32@60 18:43:17 INFO - 2016-04-19 18:43:17.893 screenresolution[2190:16483] starting screenresolution argv=screenresolution list 18:43:17 INFO - Available Modes on Display 0 18:43:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:43:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:43:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:43:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:43:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:43:18 INFO - Intel Iris: 18:43:18 INFO - Chipset Model: Intel Iris 18:43:18 INFO - Type: GPU 18:43:18 INFO - Bus: Built-In 18:43:18 INFO - VRAM (Dynamic, Max): 1536 MB 18:43:18 INFO - Vendor: Intel (0x8086) 18:43:18 INFO - Device ID: 0x0a2e 18:43:18 INFO - Revision ID: 0x0009 18:43:18 INFO - Displays: 18:43:18 INFO - Display: 18:43:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:43:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:43:18 INFO - Display Serial Number: 1600x1200 60 18:43:18 INFO - Main Display: Yes 18:43:18 INFO - Mirror: Off 18:43:18 INFO - Online: Yes 18:43:18 INFO - Rotation: Supported 18:43:18 INFO - Return code: 0 18:43:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:43:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:43:18 INFO - Python 2.7.3 18:43:18 INFO - Return code: 0 18:43:18 INFO - grabbing minidump binary from tooltool 18:43:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:43:18 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f7cee00>, '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 0x10f5f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f7c9b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:43:18 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 18:43:18 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 18:43:18 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:43:18 INFO - Return code: 0 18:43:18 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:43:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:43:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:43:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:43:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:43:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 18:43:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 18:43:19 INFO - Return code: 0 18:43:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WRjFB5zlT6iONjM4i02uLQ/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0141', '--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 18:43:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WRjFB5zlT6iONjM4i02uLQ/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0141 --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 18:43:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZA7AMP6JQL/Render', 18:43:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:43:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:43:19 INFO - 'HOME': '/Users/cltbld', 18:43:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:43:19 INFO - 'LOGNAME': 'cltbld', 18:43:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:43:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:43:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:43:19 INFO - 'MOZ_NO_REMOTE': '1', 18:43:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:43:19 INFO - 'NO_EM_RESTART': '1', 18:43:19 INFO - 'PAGER': '/bin/cat', 18:43:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:43:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:43:19 INFO - 'PWD': '/builds/slave/test', 18:43:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:43:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:43:19 INFO - 'SHELL': '/bin/bash', 18:43:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6OivNVnza5/Listeners', 18:43:19 INFO - 'TMPDIR': '/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/', 18:43:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:43:19 INFO - 'USER': 'cltbld', 18:43:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:43:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:43:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:43:19 INFO - 'XPC_FLAGS': '0x0', 18:43:19 INFO - 'XPC_SERVICE_NAME': '0', 18:43:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:43:19 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WRjFB5zlT6iONjM4i02uLQ/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0141', '--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 18:43:19 INFO - mozversion application_buildid: 20160419163752 18:43:19 INFO - mozversion application_changeset: f60bd9567a058bf0d4d5b35f0d53a46eba0aa500 18:43:19 INFO - mozversion application_display_name: Nightly 18:43:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:43:19 INFO - mozversion application_name: Firefox 18:43:19 INFO - mozversion application_remotingname: firefox 18:43:19 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 18:43:19 INFO - mozversion application_vendor: Mozilla 18:43:19 INFO - mozversion application_version: 48.0a1 18:43:19 INFO - mozversion platform_buildid: 20160419163752 18:43:19 INFO - mozversion platform_changeset: f60bd9567a058bf0d4d5b35f0d53a46eba0aa500 18:43:19 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 18:43:19 INFO - mozversion platform_version: 48.0a1 18:43:19 INFO - using testdate: 1461116599 18:43:19 INFO - actual date: 1461116599 18:43:19 INFO - starting webserver on 'localhost:52654' 18:43:19 INFO - SUITE-START | Running 2 tests 18:43:19 INFO - TEST-START | dromaeo_css 18:43:19 INFO - operating with platform_type : mac_ 18:43:19 INFO - Initialising browser for dromaeo_css test... 18:43:19 INFO - TEST-INFO | started process 2208 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/tmptJSwxg/profile http://localhost:52654/getInfo.html) 18:43:22 INFO - PROCESS | 2208 | __metrics Screen width/height:1600/1200 18:43:22 INFO - PROCESS | 2208 | colorDepth:24 18:43:22 INFO - PROCESS | 2208 | Browser inner width/height: 1024/689 18:43:22 INFO - PROCESS | 2208 | __metrics 18:43:22 INFO - PROCESS | 2208 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:43:22 ERROR - PROCESS | 2208 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:43:22 INFO - TEST-INFO | 2208: exit 0 18:43:22 INFO - Browser initialized. 18:43:22 INFO - Running cycle 1/1 for dromaeo_css test... 18:43:22 INFO - TEST-INFO | started process 2210 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/tmptJSwxg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:43:34 INFO - PROCESS | 2210 | RSS: Main: 237510656 18:43:34 INFO - PROCESS | 2210 | 18:44:25 INFO - PROCESS | 2210 | 1461116665776 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] 18:46:37 INFO - PROCESS | 2210 | Cycle 1(1): loaded http://localhost:52654/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52654/tests/dromaeo/cssquery-ext.html) 18:46:39 INFO - PROCESS | 2210 | RSS: Main: 383160320 18:46:39 INFO - PROCESS | 2210 | 18:49:42 INFO - PROCESS | 2210 | Cycle 1(1): loaded http://localhost:52654/tests/dromaeo/cssquery-ext.html (next: http://localhost:52654/tests/dromaeo/cssquery-jquery.html) 18:49:42 INFO - PROCESS | 2210 | RSS: Main: 383242240 18:49:42 INFO - PROCESS | 2210 | 18:52:45 INFO - PROCESS | 2210 | Cycle 1(1): loaded http://localhost:52654/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52654/tests/dromaeo/cssquery-mootools.html) 18:52:45 INFO - PROCESS | 2210 | RSS: Main: 377700352 18:52:45 INFO - PROCESS | 2210 | 18:55:47 INFO - PROCESS | 2210 | Cycle 1(1): loaded http://localhost:52654/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52654/tests/dromaeo/cssquery-prototype.html) 18:55:48 INFO - PROCESS | 2210 | RSS: Main: 377929728 18:55:48 INFO - PROCESS | 2210 | 18:58:51 INFO - PROCESS | 2210 | Cycle 1(1): loaded http://localhost:52654/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52654/tests/dromaeo/cssquery-yui.html) 18:58:52 INFO - PROCESS | 2210 | RSS: Main: 350019584 18:58:52 INFO - PROCESS | 2210 | 19:01:55 INFO - PROCESS | 2210 | Cycle 1(1): loaded http://localhost:52654/tests/dromaeo/cssquery-yui.html (next: http://localhost:52654/tests/dromaeo/cssquery-dojo.html) 19:01:55 INFO - PROCESS | 2210 | RSS: Main: 383246336 19:01:55 INFO - PROCESS | 2210 | 19:01:55 INFO - PROCESS | 2210 | __start_tp_report 19:01:55 INFO - PROCESS | 2210 | _x_x_mozilla_page_load 19:01:55 INFO - PROCESS | 2210 | _x_x_mozilla_page_load_details 19:01:55 INFO - PROCESS | 2210 | |i|pagename|runs| 19:01:55 INFO - PROCESS | 2210 | |0;dojo.html;3327.351166522528;3420.3672320620685;3428.262923471454;3448.706859916907;3480.773749706269;5558.972205138969;5625.54995600351;5661.575381846357;5686.289213848269;5727.341355744091;4639.652026098048;5609.298837645294;5647.068233741438;5678.119891416826;5684.317881854173;5066.316047333618;5071.416787069479;5112.616553758483;5143.331366922295;5161.793528258865;5159.097157997354;5174.223866420049;5176.275321454999;5179.844604661847;5186.247994040862;5103.23451482277;5149.6910185389;5156.53591176794;5175.792968281283;5232.8691782705355;5157.252198431225;5203.375594928632;5216.426193118754;5223.085959956992;5278.287431196778;4997.62517811162;5059.443461219263;5105.310783044279;5132.358455193101;5139.948600514022;5532.917006244909;5553.416891226444;5576.414476479952;5583.162525621149;5618.269624948772;6173.32093469718;6189.907151392733;6192.28788689302;6195.163652906845;6205.03821907605;6038.943184942617;6108.858142493628;6144.648177400964;6180.690965451754;6186.969065154691;5825.1844741736195;5854.356020837705;5856.092305692625;5878.794242201502;5886.470217680387;6156.384361563897;6206.007038873849;6226.782062627786;6235.594686345373;6244.562880598405;5960.910586341209;6078.483328917092;6093.878122437615;6119.204503414617;6129.632222066691;6171.352008039181;6185.536084793658;6198.442140207313;6199.256089269316;6202.658853763086;6121.785737499166;6185.84535386619;6186.350433204539;6188.2883468400805;6203.534734894901;6065.696715164224;6128.014278093809;6144.262688477322;6184.010558310649;6189.566730328834;4276.379924910871;4804.591609713214;4920.6801557898625;4920.926186107211;4926.359573255526;4450.777461126931;4471.105778844244;4485.618722408566;4492.213862574102;4499.392582001454;5685.573581981285;5688.772449102073;5693.373728889819;5695.829125126253;5705.885882282331;6141.416565426277;6225.315215326311;6237.625742455467;6243.43809057187;6263.812085637438;5699.829005129853;5700.087985922232;5711.057675483498;5713.342965558931;5749.367569567345;5387.299651045418;5393.406725260218;5398.73006349681;5400.702961337164;5401.891962160735;6155.907661385083;6210.596311239755;6238.844028899223;6280.780172693934;6288.119663247057;6250.499960003098;6271.843203919939;6273.059041143865;6301.905471417841;6303.275123360872;6120.995732433061;6271.090691849565;6275.686215689106;6283.465905398092;6299;6123.984276525883;6131.816045518463;6206.782762603287;6213.875722485615;6219.067139928866;5388.488093631099;6199;6229.937700622936;6237.7193026314835;6281.842953926189;5801.912971305349;5940.049592065422;6030.75876964934;6036.456718895321;6043.09353596964;5961.821145365646;6024.246969128859;6052.092186172109;6061.969690151521;6083.787067452795;5850.619709718854;5889.381614930542;5893.381194974638;5910.7635694573455;5920.73356698941;5871.060630299132;6016.338202797601;6064.3935606439;6076.969615151896;6088.551737815565;4883.951160488492;5847.970760146172;5887.3523912370465;5965.254343207099;5969.194158788681 19:01:55 INFO - PROCESS | 2210 | |1;ext.html;478.74147960101544;497.20943699517784;516.1870054663905;526.0557299647134;547.8440490564908;28823.991160309393;30293;30327.84836075819;30346.78612855486;30370;38006.23987520255;39047.60952390475;40528.78413647588;40563.985900352505;40753;8832.249258813008;8837.425567338134;9256.953715231448;9489.478078705668;9501.954784973648;8395.454295470805;8679.652813887436;9420.340576159671;9540.09369109937;9564.37831540951;11336.603218887341;11676.007539359165;11686;11688.941555292211;11698.707532311675;11325;11488.138389620728;11489.42552872353;11499.252548584314;11502.942485287562;8349.666013359458;9698.90301096987;10999;11350.886491135148;11358.091352691847;38255.42616860749;38748.61251387478;38762.03094922621;38865.41701874474;38974.805125974475;3251.235959549504;3296.489044198153;3368.6631336866362;3499.737519686034;3973.3443981742976;27471.45057098867;27630.861845690844;29116.563251551244;29147.271318217216;29163.125106246847;2758.4207316463567;2785.3454438207004;2801.1596521043807;2801.649793775778;2808.4523517914013;449037;451614;452227.47772522183;452769;456470;52578.15976440806;54517;55305.723471382385;55632.44367556353;55982.44017559854;53203.73398133062;57415;64030.6798466014;64128.679356602916;64225;16544.586385340463;16758.74861877073;16781.916090419712;16791.32834686599;16791.748123778154;12925.935370323088;13013.674658133434;13017.869821301665;13024.54414095502;13026.674333141558;14603.78094328586;15039.022463539835;15041.022333548284;15064.397424103132;16544.007359558225;5033.471485494044;6234.906476402857;6376.840578985562;6408.2630497492455;6444.548881578338;928.8513837785922;966.4877614864133;990.5195979949718;1036.56407442511;1094.2121672395863;24935.251942441755;26259;27918;30311.545326820116;30321.090367289016;1078.9028987391173;1108.8835672254459;1120.960766373173;1177.1818586082761;1280.4570492556447;15218.847811521742;15283.38866445352;15337.463188788339;15597.610059748597;15769.132697701736;617095;623558;625566;625813;626692.8665356735;50814.74592627013;58573.70713146407;58964;59559.40440595625;60041.69979150076;1477;1482.2070192447204;1482.5774654223494;1490.217635741223;1497.3636204612997;28448.43103137967;29285.560716589694;29472.852635736683;29492.852535738042;30239.546406803474;49915.001699966524;50604.74697626635;51073.744631276604;51217.23174152316;51242;478436;778242;787064;807273;808471;37710.24579508339;38349.6577619782;38754.6124538751;38795.2240955185;38875;44739.881502962686;44818.55181448144;44908.550914491745;46169;46303.76848115738;31713.841430792698;35325;37195.81402092972;38015.80992095022;38047.429288561245;32703.18242043968;36365;36365.0908727284;37267.44098838574;37296.25407491781;36331.636683633886;36473.452898206015;36638.90083297505;37047;37515.81242093772;26589;30527.694723052486;32270.83864580662;32339.838300808344;32784.1803954903;23970;24291;24535.877320613996;25315.240542783748;25432.618510721983 19:01:55 INFO - PROCESS | 2210 | |2;jquery.html;376.1423953386279;389.4314301120364;391.06925517268894;395.57000471379473;400.3354431643472;19651;19934;20609.27867524637;20666.27668031619;21220.681689774665;20462.07920643571;20701.482462938435;20715;20954.476138096554;21002.36992890212;5480.123180291154;5917.615355001911;5928.584999050068;5930.940690593104;5932.525397968152;5590.133529302964;5733.856653583673;5844.970775146118;5870.090136028923;5871.178035075093;5832;5855.970720146393;5875;5884.529237660977;5884.852878678046;5424.864378390572;5797.072468405035;5818.06910894257;5821.941780582182;5826.096954971986;5581.916271255935;5583.720813959286;5590.468905453975;5597.244372009767;5601.327840659147;12730.74538509234;13577.660558486019;20698.5860282795;23319.300420987398;24787.752122478905;2052.887091209983;2056.9280075197444;2082.0526660369496;2095.916163353464;2102.16964299101;7357.167490516136;9469.289803264714;9675.661351852721;9817.509124543743;10133.138683211935;1669.8998060116432;1676.6311411489453;1706.5711941523061;1712.5804177976352;1748.3443708609273;77133;103771;105864;112720;118366;9049.239981805613;11460.828087578693;11624.709382265342;11653.543307086615;11798.823017654742;12213.694657633452;12705.618831435071;12705.936470317589;12707.36463176856;12712.42794074269;7463.4029277657655;7546.2831031051965;7560.697572097055;8236.670533178725;8294.626741796634;8351.66593336272;8358.916410835815;8367.748967530984;8435.029971553227;8508;4091.447654566655;4277.871663850089;4292.205941900758;4320.1143765527995;4332.220200363901;1594.5011689012292;1778.6437359234992;1823.5714607067328;1837.8162183781515;1915.2817693364989;938.4979036215592;958.5063692198523;966.6616684160629;984.3650845204803;985.5663508056432;18670.719939200924;19233.84596924189;19501.512462188275;19695.212191512466;19763.912984785973;941.6562954521551;987.274353350287;999.3903718731714;1007.5163921317662;1029.4029462911494;10138.350193930179;10307.587696492206;10353;10395.740106497247;10434.82572264369;586185;591229;607862;607912;610362;132734.33632831773;134725;135702;137162;137240;1001.9560097401366;1008.6469735592631;1013.427413511349;1033.235405799703;1034.3535290443472;17347.913260433616;17423.56441088983;17593.82406175973;17623.471295861145;17760.822391775917;37064.07339816526;47294.52705473039;47467.762661186476;50346.74826625844;51241.48758512367;69903.30096699171;71137.28862711514;72705.6364718173;73487.63256183684;75858;7823.2567906048835;7968.36253099739;8058.153893841062;8122.67509299645;8144.226298501633;9394.906050939404;11981.940090299493;12052.156349055482;12424.875751242373;12555.623331300076;8123.309518690978;9581.856272156063;10536.683899483029;10656.094231990368;10963.506642201279;7955.681772729026;10262.692119236435;10411.271211015157;11088.445577721242;11159.776804464027;10773.138148947908;11063.72340691489;11269.943650281695;11495.77008459843;11509.712257193301;6954.443644508326;8033.076196237507;8059.355251579742;8260.173982601933;8480.194381533744;9366.718998430058;10786.514606842555;10937.671869843918;11118.054965327714;11244 19:01:55 INFO - PROCESS | 2210 | |3;mootools.html;1400.509821562453;1430.3992323224245;1626.2031604513784;1834.119622581161;1980.0099950024987;7803.687852485901;8360.790980225498;8658.13418658133;8666.783330416743;8682.522461264627;8235.21765432283;8557.14428557144;8569.828603427928;8574.914250857506;8575.485470871752;2120.809127178554;2332.323626148415;2339.5671800716837;2383.4756353602265;2430.1130087518104;2428.86641234732;2452.4236804350962;2455.1038870812113;2455.1407007547396;2460.692413448319;2382.332946774906;2406.855588664677;2420.044082587377;2421.9031238750517;2425.842320249178;2081.9479513012143;2296.666983287431;2377.8335516513853;2378.678878351426;2388.820838437107;2327.371609665398;2348.5889969255313;2353.682252895854;2358.752331005254;2359.9528009439887;8425;8496.447730897533;8497.532635705034;8524.06235314115;8532.36007299455;4993.325901003354;5189.143791274435;5201.635885488017;5223.3209682741;5229.973850130763;3341.599008119017;3403.0981789825714;3504.5969713483023;3515.34966031285;3524.577050753926;1030.1192480429274;1032.4424810602266;1043.5356266461429;1047.6752206815836;1051.285126114242;11329.376884271362;11531;11541.884581154165;11554.595589154424;11576.942115289454;4646.744429056367;4648.604868586141;4825.782839772219;4828.130936431476;4831.275308703653;8937.106289371011;8962.417442866194;9033.457992520469;9047.366684332033;9053.547322633842;5165.276861239429;5175.896482070413;5180.507851754077;5188.584913206934;5205.375354957429;4155.168966206771;4339.197248508973;4480.708753930984;4697.459792123881;4866.562009419169;3289.671032896691;3761.7931013793955;3773.4905787718308;3869.941950870739;3885.766853988713;1202.741410596726;1211.9939400302942;1217.8599461061915;1220.7497463020059;1231.9220681903334;775.1937984496061;855.5807654249497;887.4276091920675;906.7007887397143;908.7504681063538;4964;5458.099413596712;5692.203091567184;5753.971230143822;5779.913301300484;960.7934294126657;964.2767924056957;992.2734442070889;1058.1323314881722;1097.81984366806;3805.562360328542;3865.729398942103;3899.3760998240145;3935.940960885589;3950.6839452843706;10042.899571004342;10312.27814053009;10387;10487.947560262302;10493.160547156209;10308.072273495422;10427.895721042843;10436.947815260875;10442.477876106317;10443.530041148166;7512.511686740344;8001.039875215011;8013.398995075393;8041.597920103972;8200.917990820135;5722.542196624343;5830.416958304136;5892.528597712087;5909.320428150646;5911.674857882857;9591.568379423083;9607.855882161914;9830.311878168459;9838.704838854846;9871.703848884545;9835.262355323237;10071.848922266025;10081.243906706879;10225.181985440999;10272.229582781172;6681.866362672817;6898.827529311808;6901.378875901193;7089.0784198053925;7429.5542267464125;7211.819704507429;7295.015172951584;7809.297163255334;8101.513909165469;8114.06688230861;7128;7221.31397517235;7230.783076507713;7351.595662238628;7358.742444014434;7095.574265544084;7107.076080109553;7177.7487787927175;7194.948461327152;7246.5289756165685;6642.90035649456;6772.762953296611;6773.051772751917;6783.3555812197765;6785.9660701696175;4666.976665116653;4760.166970780169;4804.759762011955;4879.926801097916;4968.900621987612;4614.515475875119;4763.928541071956;4869.70781753096;4870.342503762088;5030.547250747451 19:01:55 INFO - PROCESS | 2210 | |4;prototype.html;310.42416317730607;319.62284504284946;323.3662022436026;327.92621660126474;332.7737138745652;6009.188759517464;6021.698915054246;6081.148639190511;6086.330503644595;6144.784932527363;5299.251247088461;5932.317783454904;5996.670183139926;6020.066889632114;6038.094285857123;4275.40144379787;4289.571042895701;4440.42274504314;4468.776561171945;4541.114482675863;3499.3876071687355;4111.97944010281;4492.7528985905765;4503.1218912312115;4513.713591626387;3743.0455233915313;4125.814338354781;4452;4506.77466126694;4515.187266292066;4188.162367526507;4278.7218830776055;4368.453943257093;4543.977280113611;4581.358609794632;3369.7809642373168;3893.7079719020903;3894.3963685628773;3906.433567132778;3914.725969182158;4759.571638552512;5733.569982251306;5752.424757524256;5768.769249230026;5847.3275573308765;4591.8392856249875;5046.772895219688;5279.023380674587;5282.894342113136;5312.203169524563;6168.352323006109;6324.5889017214195;6359.55483116182;6397.808065758035;6404;3968.5634580196156;4368.257396242647;4372.147431250907;4374.956250437487;4377.190219809314;6673.2325782535145;6684.598924064523;6686.866262674719;6709.590985892968;6763.748706489266;3930.862419815293;4271.530131685512;4291.613754762024;4295.01214720616;4311.719738217006;3824.349860523718;4208.137331846964;4334.349847522833;4358;4372.6939114261695;4345.34819777036;5110.361204849394;5129.948700513021;5142.614303927146;5165.354330708661;6414.198225221848;6444.194475690539;6460.806175814733;6492.967535162294;6517.9348206517325;1356.7150898311268;1474.6976869741677;1478.1648368671665;1502.5492352294486;1514.5986313626897;906.4425378392241;950.7432993091827;955.527014128011;962.153305091515;978.2663002747939;5747.453707172598;5765.4522820332;5893.705314734246;6010.639361638316;6023.6385816851125;6638.203415590064;6688.565243259172;6694.364035416627;6713.328667133345;6714.597124172566;5728.169415434739;5987.760489580455;6020.548458865603;6023.216981792339;6038.215032045894;2919.6496420429257;3040.422319759283;3200.6159260888844;3224.758467989841;3450.896473105811;6524.380183882524;6862.696087743313;6884.965575172192;6922.723091076401;6960.051185668021;5941.881162376728;6582.473402127818;6715.7985260442265;6792.558483698544;6923.792286231421;5679.261695979579;6110.541709371815;6638.734450621921;6908.99278418516;6968.930310696929;5726.9713651431475;5999.970000149971;6604.570702904488;6647.933520664731;6648.335166483313;6319.589226700434;6324.747010119728;6551.479479159365;6553.478100110909;6594.703238354382;6594.384375827953;6886;6889.758858440131;6894.758683446055;6934.791956241321;3781.827633433644;3915.9231211417086;3937.5274967004143;4260.8721738347895;4287.442632457761;5654.370717616328;6338.841528961812;6586;6619.702113404812;6629.635370054693;5396.244525766474;5555.388907220284;5658.660480371191;6235.220597425322;6397.1363865879375;5434.021876062188;5686.943130568641;6295.622262664072;6367.490600751836;6385.744570217325;5247.685138891678;5739.598228123991;6010.609310394811;6093.512518998381;6173.259208894961;5574.996500629763;6031.215941927521;6354.364563543609;6356.364363563606;6367.59912819179;5168;6143;6260.996481125996;6272.6236425814595;6298.559100863079 19:01:55 INFO - PROCESS | 2210 | |5;yui.html;618.7184830901939;724.7789424225609;729.110485208046;757.4773406349617;797.9082405523367;14834.629134271649;15277.618059548502;15289.388424463008;15291.388344466235;15326.54020379389;14012.29938503076;14097.718045639107;14104.788428173586;14109;14110.153390796559;891.429485129516;926.0091701878993;931.0968360690119;931.1712575807503;932.1191474057019;908.3505293714992;921.797204614987;921.889373275208;922.727795300388;925.2644147902433;950.8145911547251;951.2532661860441;954.6467806911418;955.1928620315857;955.507913424587;948.5209968965628;948.8576713492962;950.0974074629096;950.1638558068889;951.5052172870069;705.714185754765;706.929307069294;707.5153519838875;707.6108140522743;709.4253654539839;15351;15396.923015384851;15435.536933892;15441;15452.690946181126;9051.502167380791;12177.208481448766;12388.194767340094;12559.372031398485;12682.708561201098;10015.8497622535;10166.796664066751;10483.895161048367;11476.942615286953;11538.365389903553;3491.1446695559753;3497.0208341664375;3533.5583052118486;3537.7523573349877;3656.5246517952496;54719.08483203069;70253;71871;77197.22802772012;79274;7501.362384197295;9449.291303152291;9566.56950437218;9587.568559414844;10254.32188280635;21130.471738206666;21199.25802596902;21372.679409808865;21473.67789483159;21497.14011439525;7311.670974806147;7486.821766874044;7503.5497870127965;7519.8120046998165;7911.643976021093;3386.830658467116;3470.774399664014;3989.501312335958;4094.1402305516176;4121.505419349697;3553.5380400547765;3669.94495082574;3676.5588129424104;3679.9144252445594;3683.281760056817;546.9425710300441;651.8924377477758;652.5758257132807;671.2448495442627;695.7286658203304;510.691031925687;536.8926214757064;561.4301483993668;600.3186383454723;605.9787907423218;7640.656170472342;7722.382209423233;7748.612569371621;7836.568988705562;8322.334213263046;549.1159233633846;560.5319558168975;577.9566532510079;596.5168213746879;596.9552283578662;3732.757370770891;3792.27946690128;3819.6180381962135;3821.4458903458844;3838.328292548793;94401;94450;96035;96513;96781.03218967721;52995;59490.81018379608;78985.60507197428;79284;80494;35594.822025889705;59628.403715963155;59709;66472.99999999904;71413.64293178501;6184.257889053342;6760.290169532128;7622.428317876293;8099.878501822595;8175.223353781381;41093.79453102715;41807.58192418037;43634.56365436432;43656.78171609122;44766.77616611896;51752.74123629358;57682.13476797767;62831.6858415705;64289;65430;9208.355415120877;10642.627508037182;11073.335599864035;11075.778484430428;11369.54521819118;11911.225770324902;11964.461599227881;11964.64106076819;11975.700607484883;11993.100517461051;11396;11413.885861141282;11418;11438.084953203557;11455.770884582429;9330.580123894306;11402.600908968147;11623.302601843916;11856.347900865536;12288.938555307524;11433.714157146138;11450.312981221154;11815.468303926173;11930.164888457724;11940.462679179283;9253.542270344644;9576.664816731382;9790.314677972474;9859.605615775288;10129.64546240878;9173.862392063991;9523.666671666735;9680.85478717834;9753.317267791186;9935.254855885692 19:01:55 INFO - PROCESS | 2210 | __end_tp_report 19:01:55 INFO - PROCESS | 2210 | __start_cc_report 19:01:55 INFO - PROCESS | 2210 | _x_x_mozilla_cycle_collect,1305 19:01:55 INFO - PROCESS | 2210 | __end_cc_report 19:01:55 INFO - PROCESS | 2210 | __startTimestamp1461117715449__endTimestamp 19:01:55 INFO - PROCESS | 2210 | 19:01:55 INFO - PROCESS | 2210 | ------- Summary: start ------- 19:01:55 INFO - PROCESS | 2210 | Number of tests: 6 19:01:55 INFO - PROCESS | 2210 | 19:01:55 INFO - PROCESS | 2210 | [#0] dojo.html Cycles:165 Average:5689.62 Median:5889.38 stddev:634.95 (10.8%) stddev-sans-first:609.25 19:01:55 INFO - PROCESS | 2210 | Values: 3327.4 3420.4 3428.3 3448.7 3480.8 5559.0 5625.5 5661.6 5686.3 5727.3 4639.7 5609.3 5647.1 5678.1 5684.3 5066.3 5071.4 5112.6 5143.3 5161.8 5159.1 5174.2 5176.3 5179.8 5186.2 5103.2 5149.7 5156.5 5175.8 5232.9 5157.3 5203.4 5216.4 5223.1 5278.3 4997.6 5059.4 5105.3 5132.4 5139.9 5532.9 5553.4 5576.4 5583.2 5618.3 6173.3 6189.9 6192.3 6195.2 6205.0 6038.9 6108.9 6144.6 6180.7 6187.0 5825.2 5854.4 5856.1 5878.8 5886.5 6156.4 6206.0 6226.8 6235.6 6244.6 5960.9 6078.5 6093.9 6119.2 6129.6 6171.4 6185.5 6198.4 6199.3 6202.7 6121.8 6185.8 6186.4 6188.3 6203.5 6065.7 6128.0 6144.3 6184.0 6189.6 4276.4 4804.6 4920.7 4920.9 4926.4 4450.8 4471.1 4485.6 4492.2 4499.4 5685.6 5688.8 5693.4 5695.8 5705.9 6141.4 6225.3 6237.6 6243.4 6263.8 5699.8 5700.1 5711.1 5713.3 5749.4 5387.3 5393.4 5398.7 5400.7 5401.9 6155.9 6210.6 6238.8 6280.8 6288.1 6250.5 6271.8 6273.1 6301.9 6303.3 6121.0 6271.1 6275.7 6283.5 6299.0 6124.0 6131.8 6206.8 6213.9 6219.1 5388.5 6199.0 6229.9 6237.7 6281.8 5801.9 5940.0 6030.8 6036.5 6043.1 5961.8 6024.2 6052.1 6062.0 6083.8 5850.6 5889.4 5893.4 5910.8 5920.7 5871.1 6016.3 6064.4 6077.0 6088.6 4884.0 5848.0 5887.4 5965.3 5969.2 19:01:55 INFO - PROCESS | 2210 | 19:01:55 INFO - PROCESS | 2210 | [#1] ext.html Cycles:180 Average:72313.77 Median:36556.18 stddev:166044.44 (454.2%) stddev-sans-first:166422.64 19:01:55 INFO - PROCESS | 2210 | Values: 478.7 497.2 516.2 526.1 547.8 28824.0 30293.0 30327.8 30346.8 30370.0 38006.2 39047.6 40528.8 40564.0 40753.0 8832.2 8837.4 9257.0 9489.5 9502.0 8395.5 8679.7 9420.3 9540.1 9564.4 11336.6 11676.0 11686.0 11688.9 11698.7 11325.0 11488.1 11489.4 11499.3 11502.9 8349.7 9698.9 10999.0 11350.9 11358.1 38255.4 38748.6 38762.0 38865.4 38974.8 3251.2 3296.5 3368.7 3499.7 3973.3 27471.5 27630.9 29116.6 29147.3 29163.1 2758.4 2785.3 2801.2 2801.6 2808.5 449037.0 451614.0 452227.5 452769.0 456470.0 52578.2 54517.0 55305.7 55632.4 55982.4 53203.7 57415.0 64030.7 64128.7 64225.0 16544.6 16758.7 16781.9 16791.3 16791.7 12925.9 13013.7 13017.9 13024.5 13026.7 14603.8 15039.0 15041.0 15064.4 16544.0 5033.5 6234.9 6376.8 6408.3 6444.5 928.9 966.5 990.5 1036.6 1094.2 24935.3 26259.0 27918.0 30311.5 30321.1 1078.9 1108.9 1121.0 1177.2 1280.5 15218.8 15283.4 15337.5 15597.6 15769.1 617095.0 623558.0 625566.0 625813.0 626692.9 50814.7 58573.7 58964.0 59559.4 60041.7 1477.0 1482.2 1482.6 1490.2 1497.4 28448.4 29285.6 29472.9 29492.9 30239.5 49915.0 50604.7 51073.7 51217.2 51242.0 478436.0 778242.0 787064.0 807273.0 808471.0 37710.2 38349.7 38754.6 38795.2 38875.0 44739.9 44818.6 44908.6 46169.0 46303.8 31713.8 35325.0 37195.8 38015.8 38047.4 32703.2 36365.0 36365.1 37267.4 37296.3 36331.6 36473.5 36638.9 37047.0 37515.8 26589.0 30527.7 32270.8 32339.8 32784.2 23970.0 24291.0 24535.9 25315.2 25432.6 19:01:55 INFO - PROCESS | 2210 | 19:01:55 INFO - PROCESS | 2210 | [#2] jquery.html Cycles:180 Average:34098.11 Median:30925.91 stddev:100182.88 (323.9%) stddev-sans-first:100431.92 19:01:55 INFO - PROCESS | 2210 | Values: 376.1 389.4 391.1 395.6 400.3 19651.0 19934.0 20609.3 20666.3 21220.7 20462.1 20701.5 20715.0 20954.5 21002.4 5480.1 5917.6 5928.6 5930.9 5932.5 5590.1 5733.9 5845.0 5870.1 5871.2 5832.0 5856.0 5875.0 5884.5 5884.9 5424.9 5797.1 5818.1 5821.9 5826.1 5581.9 5583.7 5590.5 5597.2 5601.3 12730.7 13577.7 20698.6 23319.3 24787.8 2052.9 2056.9 2082.1 2095.9 2102.2 7357.2 9469.3 9675.7 9817.5 10133.1 1669.9 1676.6 1706.6 1712.6 1748.3 77133.0 103771.0 105864.0 112720.0 118366.0 9049.2 11460.8 11624.7 11653.5 11798.8 12213.7 12705.6 12705.9 12707.4 12712.4 7463.4 7546.3 7560.7 8236.7 8294.6 8351.7 8358.9 8367.7 8435.0 8508.0 4091.4 4277.9 4292.2 4320.1 4332.2 1594.5 1778.6 1823.6 1837.8 1915.3 938.5 958.5 966.7 984.4 985.6 18670.7 19233.8 19501.5 19695.2 19763.9 941.7 987.3 999.4 1007.5 1029.4 10138.4 10307.6 10353.0 10395.7 10434.8 586185.0 591229.0 607862.0 607912.0 610362.0 132734.3 134725.0 135702.0 137162.0 137240.0 1002.0 1008.6 1013.4 1033.2 1034.4 17347.9 17423.6 17593.8 17623.5 17760.8 37064.1 47294.5 47467.8 50346.7 51241.5 69903.3 71137.3 72705.6 73487.6 75858.0 7823.3 7968.4 8058.2 8122.7 8144.2 9394.9 11981.9 12052.2 12424.9 12555.6 8123.3 9581.9 10536.7 10656.1 10963.5 7955.7 10262.7 10411.3 11088.4 11159.8 10773.1 11063.7 11269.9 11495.8 11509.7 6954.4 8033.1 8059.4 8260.2 8480.2 9366.7 10786.5 10937.7 11118.1 11244.0 19:01:55 INFO - PROCESS | 2210 | 19:01:55 INFO - PROCESS | 2210 | [#3] mootools.html Cycles:180 Average:5547.83 Median:4868.13 stddev:3079.41 (63.3%) stddev-sans-first:3072.27 19:01:55 INFO - PROCESS | 2210 | Values: 1400.5 1430.4 1626.2 1834.1 1980.0 7803.7 8360.8 8658.1 8666.8 8682.5 8235.2 8557.1 8569.8 8574.9 8575.5 2120.8 2332.3 2339.6 2383.5 2430.1 2428.9 2452.4 2455.1 2455.1 2460.7 2382.3 2406.9 2420.0 2421.9 2425.8 2081.9 2296.7 2377.8 2378.7 2388.8 2327.4 2348.6 2353.7 2358.8 2360.0 8425.0 8496.4 8497.5 8524.1 8532.4 4993.3 5189.1 5201.6 5223.3 5230.0 3341.6 3403.1 3504.6 3515.3 3524.6 1030.1 1032.4 1043.5 1047.7 1051.3 11329.4 11531.0 11541.9 11554.6 11576.9 4646.7 4648.6 4825.8 4828.1 4831.3 8937.1 8962.4 9033.5 9047.4 9053.5 5165.3 5175.9 5180.5 5188.6 5205.4 4155.2 4339.2 4480.7 4697.5 4866.6 3289.7 3761.8 3773.5 3869.9 3885.8 1202.7 1212.0 1217.9 1220.7 1231.9 775.2 855.6 887.4 906.7 908.8 4964.0 5458.1 5692.2 5754.0 5779.9 960.8 964.3 992.3 1058.1 1097.8 3805.6 3865.7 3899.4 3935.9 3950.7 10042.9 10312.3 10387.0 10487.9 10493.2 10308.1 10427.9 10436.9 10442.5 10443.5 7512.5 8001.0 8013.4 8041.6 8200.9 5722.5 5830.4 5892.5 5909.3 5911.7 9591.6 9607.9 9830.3 9838.7 9871.7 9835.3 10071.8 10081.2 10225.2 10272.2 6681.9 6898.8 6901.4 7089.1 7429.6 7211.8 7295.0 7809.3 8101.5 8114.1 7128.0 7221.3 7230.8 7351.6 7358.7 7095.6 7107.1 7177.7 7194.9 7246.5 6642.9 6772.8 6773.1 6783.4 6786.0 4667.0 4760.2 4804.8 4879.9 4968.9 4614.5 4763.9 4869.7 4870.3 5030.5 19:01:55 INFO - PROCESS | 2210 | 19:01:55 INFO - PROCESS | 2210 | [#4] prototype.html Cycles:180 Average:5143.35 Median:5870.52 stddev:1649.14 (28.1%) stddev-sans-first:1613.38 19:01:55 INFO - PROCESS | 2210 | Values: 310.4 319.6 323.4 327.9 332.8 6009.2 6021.7 6081.1 6086.3 6144.8 5299.3 5932.3 5996.7 6020.1 6038.1 4275.4 4289.6 4440.4 4468.8 4541.1 3499.4 4112.0 4492.8 4503.1 4513.7 3743.0 4125.8 4452.0 4506.8 4515.2 4188.2 4278.7 4368.5 4544.0 4581.4 3369.8 3893.7 3894.4 3906.4 3914.7 4759.6 5733.6 5752.4 5768.8 5847.3 4591.8 5046.8 5279.0 5282.9 5312.2 6168.4 6324.6 6359.6 6397.8 6404.0 3968.6 4368.3 4372.1 4375.0 4377.2 6673.2 6684.6 6686.9 6709.6 6763.7 3930.9 4271.5 4291.6 4295.0 4311.7 3824.3 4208.1 4334.3 4358.0 4372.7 4345.3 5110.4 5129.9 5142.6 5165.4 6414.2 6444.2 6460.8 6493.0 6517.9 1356.7 1474.7 1478.2 1502.5 1514.6 906.4 950.7 955.5 962.2 978.3 5747.5 5765.5 5893.7 6010.6 6023.6 6638.2 6688.6 6694.4 6713.3 6714.6 5728.2 5987.8 6020.5 6023.2 6038.2 2919.6 3040.4 3200.6 3224.8 3450.9 6524.4 6862.7 6885.0 6922.7 6960.1 5941.9 6582.5 6715.8 6792.6 6923.8 5679.3 6110.5 6638.7 6909.0 6968.9 5727.0 6000.0 6604.6 6647.9 6648.3 6319.6 6324.7 6551.5 6553.5 6594.7 6594.4 6886.0 6889.8 6894.8 6934.8 3781.8 3915.9 3937.5 4260.9 4287.4 5654.4 6338.8 6586.0 6619.7 6629.6 5396.2 5555.4 5658.7 6235.2 6397.1 5434.0 5686.9 6295.6 6367.5 6385.7 5247.7 5739.6 6010.6 6093.5 6173.3 5575.0 6031.2 6354.4 6356.4 6367.6 5168.0 6143.0 6261.0 6272.6 6298.6 19:01:55 INFO - PROCESS | 2210 | 19:01:55 INFO - PROCESS | 2210 | [#5] yui.html Cycles:180 Average:17146.45 Median:30034.38 stddev:23840.20 (79.4%) stddev-sans-first:23874.77 19:01:55 INFO - PROCESS | 2210 | Values: 618.7 724.8 729.1 757.5 797.9 14834.6 15277.6 15289.4 15291.4 15326.5 14012.3 14097.7 14104.8 14109.0 14110.2 891.4 926.0 931.1 931.2 932.1 908.4 921.8 921.9 922.7 925.3 950.8 951.3 954.6 955.2 955.5 948.5 948.9 950.1 950.2 951.5 705.7 706.9 707.5 707.6 709.4 15351.0 15396.9 15435.5 15441.0 15452.7 9051.5 12177.2 12388.2 12559.4 12682.7 10015.8 10166.8 10483.9 11476.9 11538.4 3491.1 3497.0 3533.6 3537.8 3656.5 54719.1 70253.0 71871.0 77197.2 79274.0 7501.4 9449.3 9566.6 9587.6 10254.3 21130.5 21199.3 21372.7 21473.7 21497.1 7311.7 7486.8 7503.5 7519.8 7911.6 3386.8 3470.8 3989.5 4094.1 4121.5 3553.5 3669.9 3676.6 3679.9 3683.3 546.9 651.9 652.6 671.2 695.7 510.7 536.9 561.4 600.3 606.0 7640.7 7722.4 7748.6 7836.6 8322.3 549.1 560.5 578.0 596.5 597.0 3732.8 3792.3 3819.6 3821.4 3838.3 94401.0 94450.0 96035.0 96513.0 96781.0 52995.0 59490.8 78985.6 79284.0 80494.0 35594.8 59628.4 59709.0 66473.0 71413.6 6184.3 6760.3 7622.4 8099.9 8175.2 41093.8 41807.6 43634.6 43656.8 44766.8 51752.7 57682.1 62831.7 64289.0 65430.0 9208.4 10642.6 11073.3 11075.8 11369.5 11911.2 11964.5 11964.6 11975.7 11993.1 11396.0 11413.9 11418.0 11438.1 11455.8 9330.6 11402.6 11623.3 11856.3 12288.9 11433.7 11450.3 11815.5 11930.2 11940.5 9253.5 9576.7 9790.3 9859.6 10129.6 9173.9 9523.7 9680.9 9753.3 9935.3 19:01:55 INFO - PROCESS | 2210 | -------- Summary: end -------- 19:01:55 INFO - PROCESS | 2210 | 19:01:56 INFO - TEST-INFO | 2210: exit 0 19:01:56 INFO - TEST-OK | dromaeo_css | took 1116398ms 19:01:56 INFO - TEST-START | kraken 19:01:56 INFO - operating with platform_type : mac_ 19:01:56 INFO - Initialising browser for kraken test... 19:01:56 INFO - TEST-INFO | started process 2214 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/tmpRqCt9l/profile http://localhost:52654/getInfo.html) 19:01:58 INFO - PROCESS | 2214 | __metrics Screen width/height:1600/1200 19:01:58 INFO - PROCESS | 2214 | colorDepth:24 19:01:58 INFO - PROCESS | 2214 | Browser inner width/height: 1024/689 19:01:58 INFO - PROCESS | 2214 | __metrics 19:01:58 INFO - PROCESS | 2214 | 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" 19:01:58 ERROR - PROCESS | 2214 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 19:01:58 INFO - TEST-INFO | 2214: exit 0 19:01:58 INFO - Browser initialized. 19:01:58 INFO - Running cycle 1/1 for kraken test... 19:01:58 INFO - TEST-INFO | started process 2216 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/T/tmpRqCt9l/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:02:10 INFO - PROCESS | 2216 | RSS: Main: 237780992 19:02:10 INFO - PROCESS | 2216 | 19:02:13 INFO - PROCESS | 2216 | Cycle 1(1): loaded http://localhost:52654/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52654/tests/kraken/driver.html?testName=audio-beat-detection) 19:02:14 INFO - PROCESS | 2216 | RSS: Main: 285868032 19:02:14 INFO - PROCESS | 2216 | 19:02:17 INFO - PROCESS | 2216 | Cycle 1(1): loaded http://localhost:52654/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52654/tests/kraken/driver.html?testName=audio-dft) 19:02:17 INFO - PROCESS | 2216 | RSS: Main: 300802048 19:02:17 INFO - PROCESS | 2216 | 19:02:21 INFO - PROCESS | 2216 | Cycle 1(1): loaded http://localhost:52654/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52654/tests/kraken/driver.html?testName=audio-fft) 19:02:21 INFO - PROCESS | 2216 | RSS: Main: 388890624 19:02:21 INFO - PROCESS | 2216 | 19:02:24 INFO - PROCESS | 2216 | Cycle 1(1): loaded http://localhost:52654/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52654/tests/kraken/driver.html?testName=audio-oscillator) 19:02:24 INFO - PROCESS | 2216 | RSS: Main: 287748096 19:02:24 INFO - PROCESS | 2216 | 19:02:26 INFO - PROCESS | 2216 | Cycle 1(1): loaded http://localhost:52654/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52654/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:02:27 INFO - PROCESS | 2216 | RSS: Main: 291901440 19:02:27 INFO - PROCESS | 2216 | 19:02:30 INFO - PROCESS | 2216 | Cycle 1(1): loaded http://localhost:52654/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52654/tests/kraken/driver.html?testName=imaging-darkroom) 19:02:30 INFO - PROCESS | 2216 | RSS: Main: 330293248 19:02:30 INFO - PROCESS | 2216 | 19:02:33 INFO - PROCESS | 2216 | Cycle 1(1): loaded http://localhost:52654/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52654/tests/kraken/driver.html?testName=imaging-desaturate) 19:02:34 INFO - PROCESS | 2216 | RSS: Main: 330649600 19:02:34 INFO - PROCESS | 2216 | 19:02:37 INFO - PROCESS | 2216 | Cycle 1(1): loaded http://localhost:52654/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52654/tests/kraken/driver.html?testName=json-parse-financial) 19:02:37 INFO - PROCESS | 2216 | RSS: Main: 328687616 19:02:37 INFO - PROCESS | 2216 | 19:02:39 INFO - PROCESS | 2216 | Cycle 1(1): loaded http://localhost:52654/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52654/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:02:39 INFO - PROCESS | 2216 | RSS: Main: 319991808 19:02:39 INFO - PROCESS | 2216 | 19:02:41 INFO - PROCESS | 2216 | Cycle 1(1): loaded http://localhost:52654/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52654/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:02:42 INFO - PROCESS | 2216 | RSS: Main: 327229440 19:02:42 INFO - PROCESS | 2216 | 19:02:44 INFO - PROCESS | 2216 | Cycle 1(1): loaded http://localhost:52654/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52654/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:02:45 INFO - PROCESS | 2216 | RSS: Main: 329654272 19:02:45 INFO - PROCESS | 2216 | 19:02:48 INFO - PROCESS | 2216 | Cycle 1(1): loaded http://localhost:52654/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52654/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:02:48 INFO - PROCESS | 2216 | RSS: Main: 334319616 19:02:48 INFO - PROCESS | 2216 | 19:02:51 INFO - PROCESS | 2216 | Cycle 1(1): loaded http://localhost:52654/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52654/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:02:52 INFO - PROCESS | 2216 | RSS: Main: 332062720 19:02:52 INFO - PROCESS | 2216 | 19:02:54 INFO - PROCESS | 2216 | Cycle 1(1): loaded http://localhost:52654/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52654/tests/kraken/driver.html?testName=ai-astar) 19:02:54 INFO - PROCESS | 2216 | RSS: Main: 396226560 19:02:54 INFO - PROCESS | 2216 | 19:02:54 INFO - PROCESS | 2216 | __start_tp_report 19:02:54 INFO - PROCESS | 2216 | _x_x_mozilla_page_load 19:02:54 INFO - PROCESS | 2216 | _x_x_mozilla_page_load_details 19:02:54 INFO - PROCESS | 2216 | |i|pagename|runs| 19:02:54 INFO - PROCESS | 2216 | |0;ai-astar;113;111;111;112;113;111;117;113;114;113 19:02:54 INFO - PROCESS | 2216 | |1;audio-beat-detection;156;122;143;169;121;147;129;129;208;147 19:02:54 INFO - PROCESS | 2216 | |2;audio-dft;188;187;187;190;268;200;183;185;185;185 19:02:54 INFO - PROCESS | 2216 | |3;audio-fft;79;72;86;103;82;87;85;85;86;55 19:02:54 INFO - PROCESS | 2216 | |4;audio-oscillator;95;136;181;98;78;79;127;77;79;127 19:02:54 INFO - PROCESS | 2216 | |5;imaging-gaussian-blur;91;88;91;89;92;87;91;88;91;88 19:02:54 INFO - PROCESS | 2216 | |6;imaging-darkroom;95;95;95;96;95;95;96;96;96;96 19:02:54 INFO - PROCESS | 2216 | |7;imaging-desaturate;85;87;86;87;84;84;86;86;87;86 19:02:54 INFO - PROCESS | 2216 | |8;json-parse-financial;59;63;62;59;59;61;60;81;62;57 19:02:54 INFO - PROCESS | 2216 | |9;json-stringify-tinderbox;63;64;77;78;71;68;68;68;68;69 19:02:54 INFO - PROCESS | 2216 | |10;stanford-crypto-aes;84;96;103;77;81;79;80;81;82;83 19:02:54 INFO - PROCESS | 2216 | |11;stanford-crypto-ccm;167;137;138;137;138;156;149;127;174;142 19:02:54 INFO - PROCESS | 2216 | |12;stanford-crypto-pbkdf2;173;169;170;171;170;177;220;220;176;175 19:02:54 INFO - PROCESS | 2216 | |13;stanford-crypto-sha256-iterative;69;68;69;67;67;70;66;69;69;68 19:02:54 INFO - PROCESS | 2216 | __end_tp_report 19:02:54 INFO - PROCESS | 2216 | __start_cc_report 19:02:54 INFO - PROCESS | 2216 | _x_x_mozilla_cycle_collect,1993 19:02:54 INFO - PROCESS | 2216 | __end_cc_report 19:02:54 INFO - PROCESS | 2216 | __startTimestamp1461117774346__endTimestamp 19:02:54 INFO - PROCESS | 2216 | 19:02:54 INFO - PROCESS | 2216 | ------- Summary: start ------- 19:02:54 INFO - PROCESS | 2216 | Number of tests: 14 19:02:54 INFO - PROCESS | 2216 | 19:02:54 INFO - PROCESS | 2216 | [#0] ai-astar Cycles:10 Average:112.80 Median:113.00 stddev:1.81 (1.6%) stddev-sans-first:1.92 19:02:54 INFO - PROCESS | 2216 | Values: 113.0 111.0 111.0 112.0 113.0 111.0 117.0 113.0 114.0 113.0 19:02:54 INFO - PROCESS | 2216 | 19:02:54 INFO - PROCESS | 2216 | [#1] audio-beat-detection Cycles:10 Average:147.10 Median:147.00 stddev:26.35 (17.9%) stddev-sans-first:27.76 19:02:54 INFO - PROCESS | 2216 | Values: 156.0 122.0 143.0 169.0 121.0 147.0 129.0 129.0 208.0 147.0 19:02:54 INFO - PROCESS | 2216 | 19:02:54 INFO - PROCESS | 2216 | [#2] audio-dft Cycles:10 Average:195.80 Median:187.50 stddev:25.81 (13.8%) stddev-sans-first:27.22 19:02:54 INFO - PROCESS | 2216 | Values: 188.0 187.0 187.0 190.0 268.0 200.0 183.0 185.0 185.0 185.0 19:02:54 INFO - PROCESS | 2216 | 19:02:54 INFO - PROCESS | 2216 | [#3] audio-fft Cycles:10 Average:82.00 Median:85.00 stddev:12.27 (14.4%) stddev-sans-first:12.96 19:02:54 INFO - PROCESS | 2216 | Values: 79.0 72.0 86.0 103.0 82.0 87.0 85.0 85.0 86.0 55.0 19:02:54 INFO - PROCESS | 2216 | 19:02:54 INFO - PROCESS | 2216 | [#4] audio-oscillator Cycles:10 Average:107.70 Median:78.50 stddev:34.39 (43.8%) stddev-sans-first:36.17 19:02:54 INFO - PROCESS | 2216 | Values: 95.0 136.0 181.0 98.0 78.0 79.0 127.0 77.0 79.0 127.0 19:02:54 INFO - PROCESS | 2216 | 19:02:54 INFO - PROCESS | 2216 | [#5] imaging-gaussian-blur Cycles:10 Average:89.60 Median:91.00 stddev:1.78 (2.0%) stddev-sans-first:1.81 19:02:54 INFO - PROCESS | 2216 | Values: 91.0 88.0 91.0 89.0 92.0 87.0 91.0 88.0 91.0 88.0 19:02:54 INFO - PROCESS | 2216 | 19:02:54 INFO - PROCESS | 2216 | [#6] imaging-darkroom Cycles:10 Average:95.50 Median:96.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 19:02:54 INFO - PROCESS | 2216 | Values: 95.0 95.0 95.0 96.0 95.0 95.0 96.0 96.0 96.0 96.0 19:02:54 INFO - PROCESS | 2216 | 19:02:54 INFO - PROCESS | 2216 | [#7] imaging-desaturate Cycles:10 Average:85.80 Median:86.00 stddev:1.14 (1.3%) stddev-sans-first:1.17 19:02:54 INFO - PROCESS | 2216 | Values: 85.0 87.0 86.0 87.0 84.0 84.0 86.0 86.0 87.0 86.0 19:02:54 INFO - PROCESS | 2216 | 19:02:54 INFO - PROCESS | 2216 | [#8] json-parse-financial Cycles:10 Average:62.30 Median:61.50 stddev:6.82 (11.1%) stddev-sans-first:7.12 19:02:54 INFO - PROCESS | 2216 | Values: 59.0 63.0 62.0 59.0 59.0 61.0 60.0 81.0 62.0 57.0 19:02:54 INFO - PROCESS | 2216 | 19:02:54 INFO - PROCESS | 2216 | [#9] json-stringify-tinderbox Cycles:10 Average:69.40 Median:68.50 stddev:4.86 (7.1%) stddev-sans-first:4.57 19:02:54 INFO - PROCESS | 2216 | Values: 63.0 64.0 77.0 78.0 71.0 68.0 68.0 68.0 68.0 69.0 19:02:54 INFO - PROCESS | 2216 | 19:02:54 INFO - PROCESS | 2216 | [#10] stanford-crypto-aes Cycles:10 Average:84.60 Median:81.50 stddev:8.26 (10.1%) stddev-sans-first:8.76 19:02:54 INFO - PROCESS | 2216 | Values: 84.0 96.0 103.0 77.0 81.0 79.0 80.0 81.0 82.0 83.0 19:02:54 INFO - PROCESS | 2216 | 19:02:54 INFO - PROCESS | 2216 | [#11] stanford-crypto-ccm Cycles:10 Average:146.50 Median:145.50 stddev:14.90 (10.2%) stddev-sans-first:13.84 19:02:54 INFO - PROCESS | 2216 | Values: 167.0 137.0 138.0 137.0 138.0 156.0 149.0 127.0 174.0 142.0 19:02:54 INFO - PROCESS | 2216 | 19:02:54 INFO - PROCESS | 2216 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.10 Median:175.50 stddev:20.16 (11.5%) stddev-sans-first:21.11 19:02:54 INFO - PROCESS | 2216 | Values: 173.0 169.0 170.0 171.0 170.0 177.0 220.0 220.0 176.0 175.0 19:02:54 INFO - PROCESS | 2216 | 19:02:54 INFO - PROCESS | 2216 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.20 Median:69.00 stddev:1.23 (1.8%) stddev-sans-first:1.27 19:02:54 INFO - PROCESS | 2216 | Values: 69.0 68.0 69.0 67.0 67.0 70.0 66.0 69.0 69.0 68.0 19:02:54 INFO - PROCESS | 2216 | -------- Summary: end -------- 19:02:54 INFO - PROCESS | 2216 | 19:02:55 INFO - TEST-INFO | 2216: exit 0 19:02:55 INFO - TEST-OK | kraken | took 59098ms 19:02:55 INFO - SUITE-END | took 1175s 19:02:55 INFO - Completed test suite (00:19:35) 19:02:55 INFO - javascript benchmark 19:02:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3327.351166522528, 3420.3672320620685, 3428.262923471454, 3448.706859916907, 3480.773749706269, 5558.972205138969, 5625.54995600351, 5661.575381846357, 5686.289213848269, 5727.341355744091, 4639.652026098048, 5609.298837645294, 5647.068233741438, 5678.119891416826, 5684.317881854173, 5066.316047333618, 5071.416787069479, 5112.616553758483, 5143.331366922295, 5161.793528258865, 5159.097157997354, 5174.223866420049, 5176.275321454999, 5179.844604661847, 5186.247994040862, 5103.23451482277, 5149.6910185389, 5156.53591176794, 5175.792968281283, 5232.8691782705355, 5157.252198431225, 5203.375594928632, 5216.426193118754, 5223.085959956992, 5278.287431196778, 4997.62517811162, 5059.443461219263, 5105.310783044279, 5132.358455193101, 5139.948600514022, 5532.917006244909, 5553.416891226444, 5576.414476479952, 5583.162525621149, 5618.269624948772, 6173.32093469718, 6189.907151392733, 6192.28788689302, 6195.163652906845, 6205.03821907605, 6038.943184942617, 6108.858142493628, 6144.648177400964, 6180.690965451754, 6186.969065154691, 5825.1844741736195, 5854.356020837705, 5856.092305692625, 5878.794242201502, 5886.470217680387, 6156.384361563897, 6206.007038873849, 6226.782062627786, 6235.594686345373, 6244.562880598405, 5960.910586341209, 6078.483328917092, 6093.878122437615, 6119.204503414617, 6129.632222066691, 6171.352008039181, 6185.536084793658, 6198.442140207313, 6199.256089269316, 6202.658853763086, 6121.785737499166, 6185.84535386619, 6186.350433204539, 6188.2883468400805, 6203.534734894901, 6065.696715164224, 6128.014278093809, 6144.262688477322, 6184.010558310649, 6189.566730328834, 4276.379924910871, 4804.591609713214, 4920.6801557898625, 4920.926186107211, 4926.359573255526, 4450.777461126931, 4471.105778844244, 4485.618722408566, 4492.213862574102, 4499.392582001454, 5685.573581981285, 5688.772449102073, 5693.373728889819, 5695.829125126253, 5705.885882282331, 6141.416565426277, 6225.315215326311, 6237.625742455467, 6243.43809057187, 6263.812085637438, 5699.829005129853, 5700.087985922232, 5711.057675483498, 5713.342965558931, 5749.367569567345, 5387.299651045418, 5393.406725260218, 5398.73006349681, 5400.702961337164, 5401.891962160735, 6155.907661385083, 6210.596311239755, 6238.844028899223, 6280.780172693934, 6288.119663247057, 6250.499960003098, 6271.843203919939, 6273.059041143865, 6301.905471417841, 6303.275123360872, 6120.995732433061, 6271.090691849565, 6275.686215689106, 6283.465905398092, 6299.0, 6123.984276525883, 6131.816045518463, 6206.782762603287, 6213.875722485615, 6219.067139928866, 5388.488093631099, 6199.0, 6229.937700622936, 6237.7193026314835, 6281.842953926189, 5801.912971305349, 5940.049592065422, 6030.75876964934, 6036.456718895321, 6043.09353596964, 5961.821145365646, 6024.246969128859, 6052.092186172109, 6061.969690151521, 6083.787067452795, 5850.619709718854, 5889.381614930542, 5893.381194974638, 5910.7635694573455, 5920.73356698941, 5871.060630299132, 6016.338202797601, 6064.3935606439, 6076.969615151896, 6088.551737815565, 4883.951160488492, 5847.970760146172, 5887.3523912370465, 5965.254343207099, 5969.194158788681], "value": 5650.107625836964, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [478.74147960101544, 497.20943699517784, 516.1870054663905, 526.0557299647134, 547.8440490564908, 28823.991160309393, 30293.0, 30327.84836075819, 30346.78612855486, 30370.0, 38006.23987520255, 39047.60952390475, 40528.78413647588, 40563.985900352505, 40753.0, 8832.249258813008, 8837.425567338134, 9256.953715231448, 9489.478078705668, 9501.954784973648, 8395.454295470805, 8679.652813887436, 9420.340576159671, 9540.09369109937, 9564.37831540951, 11336.603218887341, 11676.007539359165, 11686.0, 11688.941555292211, 11698.707532311675, 11325.0, 11488.138389620728, 11489.42552872353, 11499.252548584314, 11502.942485287562, 8349.666013359458, 9698.90301096987, 10999.0, 11350.886491135148, 11358.091352691847, 38255.42616860749, 38748.61251387478, 38762.03094922621, 38865.41701874474, 38974.805125974475, 3251.235959549504, 3296.489044198153, 3368.6631336866362, 3499.737519686034, 3973.3443981742976, 27471.45057098867, 27630.861845690844, 29116.563251551244, 29147.271318217216, 29163.125106246847, 2758.4207316463567, 2785.3454438207004, 2801.1596521043807, 2801.649793775778, 2808.4523517914013, 449037.0, 451614.0, 452227.47772522183, 452769.0, 456470.0, 52578.15976440806, 54517.0, 55305.723471382385, 55632.44367556353, 55982.44017559854, 53203.73398133062, 57415.0, 64030.6798466014, 64128.679356602916, 64225.0, 16544.586385340463, 16758.74861877073, 16781.916090419712, 16791.32834686599, 16791.748123778154, 12925.935370323088, 13013.674658133434, 13017.869821301665, 13024.54414095502, 13026.674333141558, 14603.78094328586, 15039.022463539835, 15041.022333548284, 15064.397424103132, 16544.007359558225, 5033.471485494044, 6234.906476402857, 6376.840578985562, 6408.2630497492455, 6444.548881578338, 928.8513837785922, 966.4877614864133, 990.5195979949718, 1036.56407442511, 1094.2121672395863, 24935.251942441755, 26259.0, 27918.0, 30311.545326820116, 30321.090367289016, 1078.9028987391173, 1108.8835672254459, 1120.960766373173, 1177.1818586082761, 1280.4570492556447, 15218.847811521742, 15283.38866445352, 15337.463188788339, 15597.610059748597, 15769.132697701736, 617095.0, 623558.0, 625566.0, 625813.0, 626692.8665356735, 50814.74592627013, 58573.70713146407, 58964.0, 59559.40440595625, 60041.69979150076, 1477.0, 1482.2070192447204, 1482.5774654223494, 1490.217635741223, 1497.3636204612997, 28448.43103137967, 29285.560716589694, 29472.852635736683, 29492.852535738042, 30239.546406803474, 49915.001699966524, 50604.74697626635, 51073.744631276604, 51217.23174152316, 51242.0, 478436.0, 778242.0, 787064.0, 807273.0, 808471.0, 37710.24579508339, 38349.6577619782, 38754.6124538751, 38795.2240955185, 38875.0, 44739.881502962686, 44818.55181448144, 44908.550914491745, 46169.0, 46303.76848115738, 31713.841430792698, 35325.0, 37195.81402092972, 38015.80992095022, 38047.429288561245, 32703.18242043968, 36365.0, 36365.0908727284, 37267.44098838574, 37296.25407491781, 36331.636683633886, 36473.452898206015, 36638.90083297505, 37047.0, 37515.81242093772, 26589.0, 30527.694723052486, 32270.83864580662, 32339.838300808344, 32784.1803954903, 23970.0, 24291.0, 24535.877320613996, 25315.240542783748, 25432.618510721983], "value": 19741.68860717538, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.1423953386279, 389.4314301120364, 391.06925517268894, 395.57000471379473, 400.3354431643472, 19651.0, 19934.0, 20609.27867524637, 20666.27668031619, 21220.681689774665, 20462.07920643571, 20701.482462938435, 20715.0, 20954.476138096554, 21002.36992890212, 5480.123180291154, 5917.615355001911, 5928.584999050068, 5930.940690593104, 5932.525397968152, 5590.133529302964, 5733.856653583673, 5844.970775146118, 5870.090136028923, 5871.178035075093, 5832.0, 5855.970720146393, 5875.0, 5884.529237660977, 5884.852878678046, 5424.864378390572, 5797.072468405035, 5818.06910894257, 5821.941780582182, 5826.096954971986, 5581.916271255935, 5583.720813959286, 5590.468905453975, 5597.244372009767, 5601.327840659147, 12730.74538509234, 13577.660558486019, 20698.5860282795, 23319.300420987398, 24787.752122478905, 2052.887091209983, 2056.9280075197444, 2082.0526660369496, 2095.916163353464, 2102.16964299101, 7357.167490516136, 9469.289803264714, 9675.661351852721, 9817.509124543743, 10133.138683211935, 1669.8998060116432, 1676.6311411489453, 1706.5711941523061, 1712.5804177976352, 1748.3443708609273, 77133.0, 103771.0, 105864.0, 112720.0, 118366.0, 9049.239981805613, 11460.828087578693, 11624.709382265342, 11653.543307086615, 11798.823017654742, 12213.694657633452, 12705.618831435071, 12705.936470317589, 12707.36463176856, 12712.42794074269, 7463.4029277657655, 7546.2831031051965, 7560.697572097055, 8236.670533178725, 8294.626741796634, 8351.66593336272, 8358.916410835815, 8367.748967530984, 8435.029971553227, 8508.0, 4091.447654566655, 4277.871663850089, 4292.205941900758, 4320.1143765527995, 4332.220200363901, 1594.5011689012292, 1778.6437359234992, 1823.5714607067328, 1837.8162183781515, 1915.2817693364989, 938.4979036215592, 958.5063692198523, 966.6616684160629, 984.3650845204803, 985.5663508056432, 18670.719939200924, 19233.84596924189, 19501.512462188275, 19695.212191512466, 19763.912984785973, 941.6562954521551, 987.274353350287, 999.3903718731714, 1007.5163921317662, 1029.4029462911494, 10138.350193930179, 10307.587696492206, 10353.0, 10395.740106497247, 10434.82572264369, 586185.0, 591229.0, 607862.0, 607912.0, 610362.0, 132734.33632831773, 134725.0, 135702.0, 137162.0, 137240.0, 1001.9560097401366, 1008.6469735592631, 1013.427413511349, 1033.235405799703, 1034.3535290443472, 17347.913260433616, 17423.56441088983, 17593.82406175973, 17623.471295861145, 17760.822391775917, 37064.07339816526, 47294.52705473039, 47467.762661186476, 50346.74826625844, 51241.48758512367, 69903.30096699171, 71137.28862711514, 72705.6364718173, 73487.63256183684, 75858.0, 7823.2567906048835, 7968.36253099739, 8058.153893841062, 8122.67509299645, 8144.226298501633, 9394.906050939404, 11981.940090299493, 12052.156349055482, 12424.875751242373, 12555.623331300076, 8123.309518690978, 9581.856272156063, 10536.683899483029, 10656.094231990368, 10963.506642201279, 7955.681772729026, 10262.692119236435, 10411.271211015157, 11088.445577721242, 11159.776804464027, 10773.138148947908, 11063.72340691489, 11269.943650281695, 11495.77008459843, 11509.712257193301, 6954.443644508326, 8033.076196237507, 8059.355251579742, 8260.173982601933, 8480.194381533744, 9366.718998430058, 10786.514606842555, 10937.671869843918, 11118.054965327714, 11244.0], "value": 9153.615895673016, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1400.509821562453, 1430.3992323224245, 1626.2031604513784, 1834.119622581161, 1980.0099950024987, 7803.687852485901, 8360.790980225498, 8658.13418658133, 8666.783330416743, 8682.522461264627, 8235.21765432283, 8557.14428557144, 8569.828603427928, 8574.914250857506, 8575.485470871752, 2120.809127178554, 2332.323626148415, 2339.5671800716837, 2383.4756353602265, 2430.1130087518104, 2428.86641234732, 2452.4236804350962, 2455.1038870812113, 2455.1407007547396, 2460.692413448319, 2382.332946774906, 2406.855588664677, 2420.044082587377, 2421.9031238750517, 2425.842320249178, 2081.9479513012143, 2296.666983287431, 2377.8335516513853, 2378.678878351426, 2388.820838437107, 2327.371609665398, 2348.5889969255313, 2353.682252895854, 2358.752331005254, 2359.9528009439887, 8425.0, 8496.447730897533, 8497.532635705034, 8524.06235314115, 8532.36007299455, 4993.325901003354, 5189.143791274435, 5201.635885488017, 5223.3209682741, 5229.973850130763, 3341.599008119017, 3403.0981789825714, 3504.5969713483023, 3515.34966031285, 3524.577050753926, 1030.1192480429274, 1032.4424810602266, 1043.5356266461429, 1047.6752206815836, 1051.285126114242, 11329.376884271362, 11531.0, 11541.884581154165, 11554.595589154424, 11576.942115289454, 4646.744429056367, 4648.604868586141, 4825.782839772219, 4828.130936431476, 4831.275308703653, 8937.106289371011, 8962.417442866194, 9033.457992520469, 9047.366684332033, 9053.547322633842, 5165.276861239429, 5175.896482070413, 5180.507851754077, 5188.584913206934, 5205.375354957429, 4155.168966206771, 4339.197248508973, 4480.708753930984, 4697.459792123881, 4866.562009419169, 3289.671032896691, 3761.7931013793955, 3773.4905787718308, 3869.941950870739, 3885.766853988713, 1202.741410596726, 1211.9939400302942, 1217.8599461061915, 1220.7497463020059, 1231.9220681903334, 775.1937984496061, 855.5807654249497, 887.4276091920675, 906.7007887397143, 908.7504681063538, 4964.0, 5458.099413596712, 5692.203091567184, 5753.971230143822, 5779.913301300484, 960.7934294126657, 964.2767924056957, 992.2734442070889, 1058.1323314881722, 1097.81984366806, 3805.562360328542, 3865.729398942103, 3899.3760998240145, 3935.940960885589, 3950.6839452843706, 10042.899571004342, 10312.27814053009, 10387.0, 10487.947560262302, 10493.160547156209, 10308.072273495422, 10427.895721042843, 10436.947815260875, 10442.477876106317, 10443.530041148166, 7512.511686740344, 8001.039875215011, 8013.398995075393, 8041.597920103972, 8200.917990820135, 5722.542196624343, 5830.416958304136, 5892.528597712087, 5909.320428150646, 5911.674857882857, 9591.568379423083, 9607.855882161914, 9830.311878168459, 9838.704838854846, 9871.703848884545, 9835.262355323237, 10071.848922266025, 10081.243906706879, 10225.181985440999, 10272.229582781172, 6681.866362672817, 6898.827529311808, 6901.378875901193, 7089.0784198053925, 7429.5542267464125, 7211.819704507429, 7295.015172951584, 7809.297163255334, 8101.513909165469, 8114.06688230861, 7128.0, 7221.31397517235, 7230.783076507713, 7351.595662238628, 7358.742444014434, 7095.574265544084, 7107.076080109553, 7177.7487787927175, 7194.948461327152, 7246.5289756165685, 6642.90035649456, 6772.762953296611, 6773.051772751917, 6783.3555812197765, 6785.9660701696175, 4666.976665116653, 4760.166970780169, 4804.759762011955, 4879.926801097916, 4968.900621987612, 4614.515475875119, 4763.928541071956, 4869.70781753096, 4870.342503762088, 5030.547250747451], "value": 4488.62247548016, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [310.42416317730607, 319.62284504284946, 323.3662022436026, 327.92621660126474, 332.7737138745652, 6009.188759517464, 6021.698915054246, 6081.148639190511, 6086.330503644595, 6144.784932527363, 5299.251247088461, 5932.317783454904, 5996.670183139926, 6020.066889632114, 6038.094285857123, 4275.40144379787, 4289.571042895701, 4440.42274504314, 4468.776561171945, 4541.114482675863, 3499.3876071687355, 4111.97944010281, 4492.7528985905765, 4503.1218912312115, 4513.713591626387, 3743.0455233915313, 4125.814338354781, 4452.0, 4506.77466126694, 4515.187266292066, 4188.162367526507, 4278.7218830776055, 4368.453943257093, 4543.977280113611, 4581.358609794632, 3369.7809642373168, 3893.7079719020903, 3894.3963685628773, 3906.433567132778, 3914.725969182158, 4759.571638552512, 5733.569982251306, 5752.424757524256, 5768.769249230026, 5847.3275573308765, 4591.8392856249875, 5046.772895219688, 5279.023380674587, 5282.894342113136, 5312.203169524563, 6168.352323006109, 6324.5889017214195, 6359.55483116182, 6397.808065758035, 6404.0, 3968.5634580196156, 4368.257396242647, 4372.147431250907, 4374.956250437487, 4377.190219809314, 6673.2325782535145, 6684.598924064523, 6686.866262674719, 6709.590985892968, 6763.748706489266, 3930.862419815293, 4271.530131685512, 4291.613754762024, 4295.01214720616, 4311.719738217006, 3824.349860523718, 4208.137331846964, 4334.349847522833, 4358.0, 4372.6939114261695, 4345.34819777036, 5110.361204849394, 5129.948700513021, 5142.614303927146, 5165.354330708661, 6414.198225221848, 6444.194475690539, 6460.806175814733, 6492.967535162294, 6517.9348206517325, 1356.7150898311268, 1474.6976869741677, 1478.1648368671665, 1502.5492352294486, 1514.5986313626897, 906.4425378392241, 950.7432993091827, 955.527014128011, 962.153305091515, 978.2663002747939, 5747.453707172598, 5765.4522820332, 5893.705314734246, 6010.639361638316, 6023.6385816851125, 6638.203415590064, 6688.565243259172, 6694.364035416627, 6713.328667133345, 6714.597124172566, 5728.169415434739, 5987.760489580455, 6020.548458865603, 6023.216981792339, 6038.215032045894, 2919.6496420429257, 3040.422319759283, 3200.6159260888844, 3224.758467989841, 3450.896473105811, 6524.380183882524, 6862.696087743313, 6884.965575172192, 6922.723091076401, 6960.051185668021, 5941.881162376728, 6582.473402127818, 6715.7985260442265, 6792.558483698544, 6923.792286231421, 5679.261695979579, 6110.541709371815, 6638.734450621921, 6908.99278418516, 6968.930310696929, 5726.9713651431475, 5999.970000149971, 6604.570702904488, 6647.933520664731, 6648.335166483313, 6319.589226700434, 6324.747010119728, 6551.479479159365, 6553.478100110909, 6594.703238354382, 6594.384375827953, 6886.0, 6889.758858440131, 6894.758683446055, 6934.791956241321, 3781.827633433644, 3915.9231211417086, 3937.5274967004143, 4260.8721738347895, 4287.442632457761, 5654.370717616328, 6338.841528961812, 6586.0, 6619.702113404812, 6629.635370054693, 5396.244525766474, 5555.388907220284, 5658.660480371191, 6235.220597425322, 6397.1363865879375, 5434.021876062188, 5686.943130568641, 6295.622262664072, 6367.490600751836, 6385.744570217325, 5247.685138891678, 5739.598228123991, 6010.609310394811, 6093.512518998381, 6173.259208894961, 5574.996500629763, 6031.215941927521, 6354.364563543609, 6356.364363563606, 6367.59912819179, 5168.0, 6143.0, 6260.996481125996, 6272.6236425814595, 6298.559100863079], "value": 4604.508010619213, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [618.7184830901939, 724.7789424225609, 729.110485208046, 757.4773406349617, 797.9082405523367, 14834.629134271649, 15277.618059548502, 15289.388424463008, 15291.388344466235, 15326.54020379389, 14012.29938503076, 14097.718045639107, 14104.788428173586, 14109.0, 14110.153390796559, 891.429485129516, 926.0091701878993, 931.0968360690119, 931.1712575807503, 932.1191474057019, 908.3505293714992, 921.797204614987, 921.889373275208, 922.727795300388, 925.2644147902433, 950.8145911547251, 951.2532661860441, 954.6467806911418, 955.1928620315857, 955.507913424587, 948.5209968965628, 948.8576713492962, 950.0974074629096, 950.1638558068889, 951.5052172870069, 705.714185754765, 706.929307069294, 707.5153519838875, 707.6108140522743, 709.4253654539839, 15351.0, 15396.923015384851, 15435.536933892, 15441.0, 15452.690946181126, 9051.502167380791, 12177.208481448766, 12388.194767340094, 12559.372031398485, 12682.708561201098, 10015.8497622535, 10166.796664066751, 10483.895161048367, 11476.942615286953, 11538.365389903553, 3491.1446695559753, 3497.0208341664375, 3533.5583052118486, 3537.7523573349877, 3656.5246517952496, 54719.08483203069, 70253.0, 71871.0, 77197.22802772012, 79274.0, 7501.362384197295, 9449.291303152291, 9566.56950437218, 9587.568559414844, 10254.32188280635, 21130.471738206666, 21199.25802596902, 21372.679409808865, 21473.67789483159, 21497.14011439525, 7311.670974806147, 7486.821766874044, 7503.5497870127965, 7519.8120046998165, 7911.643976021093, 3386.830658467116, 3470.774399664014, 3989.501312335958, 4094.1402305516176, 4121.505419349697, 3553.5380400547765, 3669.94495082574, 3676.5588129424104, 3679.9144252445594, 3683.281760056817, 546.9425710300441, 651.8924377477758, 652.5758257132807, 671.2448495442627, 695.7286658203304, 510.691031925687, 536.8926214757064, 561.4301483993668, 600.3186383454723, 605.9787907423218, 7640.656170472342, 7722.382209423233, 7748.612569371621, 7836.568988705562, 8322.334213263046, 549.1159233633846, 560.5319558168975, 577.9566532510079, 596.5168213746879, 596.9552283578662, 3732.757370770891, 3792.27946690128, 3819.6180381962135, 3821.4458903458844, 3838.328292548793, 94401.0, 94450.0, 96035.0, 96513.0, 96781.03218967721, 52995.0, 59490.81018379608, 78985.60507197428, 79284.0, 80494.0, 35594.822025889705, 59628.403715963155, 59709.0, 66472.99999999904, 71413.64293178501, 6184.257889053342, 6760.290169532128, 7622.428317876293, 8099.878501822595, 8175.223353781381, 41093.79453102715, 41807.58192418037, 43634.56365436432, 43656.78171609122, 44766.77616611896, 51752.74123629358, 57682.13476797767, 62831.6858415705, 64289.0, 65430.0, 9208.355415120877, 10642.627508037182, 11073.335599864035, 11075.778484430428, 11369.54521819118, 11911.225770324902, 11964.461599227881, 11964.64106076819, 11975.700607484883, 11993.100517461051, 11396.0, 11413.885861141282, 11418.0, 11438.084953203557, 11455.770884582429, 9330.580123894306, 11402.600908968147, 11623.302601843916, 11856.347900865536, 12288.938555307524, 11433.714157146138, 11450.312981221154, 11815.468303926173, 11930.164888457724, 11940.462679179283, 9253.542270344644, 9576.664816731382, 9790.314677972474, 9859.605615775288, 10129.64546240878, 9173.862392063991, 9523.666671666735, 9680.85478717834, 9753.317267791186, 9935.254855885692], "value": 6754.2898999966965, "unit": "score"}], "name": "dromaeo_css", "value": 7227.561711492504, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 111.0, 111.0, 112.0, 113.0, 111.0, 117.0, 113.0, 114.0, 113.0], "value": 112.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 122.0, 143.0, 169.0, 121.0, 147.0, 129.0, 129.0, 208.0, 147.0], "value": 147.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 187.0, 187.0, 190.0, 268.0, 200.0, 183.0, 185.0, 185.0, 185.0], "value": 195.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 72.0, 86.0, 103.0, 82.0, 87.0, 85.0, 85.0, 86.0, 55.0], "value": 82.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 136.0, 181.0, 98.0, 78.0, 79.0, 127.0, 77.0, 79.0, 127.0], "value": 107.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 88.0, 91.0, 89.0, 92.0, 87.0, 91.0, 88.0, 91.0, 88.0], "value": 89.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 96.0, 96.0, 96.0, 96.0], "value": 95.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 86.0, 87.0, 84.0, 84.0, 86.0, 86.0, 87.0, 86.0], "value": 85.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 63.0, 62.0, 59.0, 59.0, 61.0, 60.0, 81.0, 62.0, 57.0], "value": 62.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 77.0, 78.0, 71.0, 68.0, 68.0, 68.0, 68.0, 69.0], "value": 69.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 96.0, 103.0, 77.0, 81.0, 79.0, 80.0, 81.0, 82.0, 83.0], "value": 84.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 137.0, 138.0, 137.0, 138.0, 156.0, 149.0, 127.0, 174.0, 142.0], "value": 146.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 169.0, 170.0, 171.0, 170.0, 177.0, 220.0, 220.0, 176.0, 175.0], "value": 182.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 69.0, 67.0, 67.0, 70.0, 66.0, 69.0, 69.0, 68.0], "value": 68.2, "unit": "score"}], "name": "kraken", "value": 1529.3999999999999, "alertThreshold": 2.0}]} 19:02:55 INFO - Return code: 0 19:02:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 19:02:55 ERROR - # TBPL SUCCESS # 19:02:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:02:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:02:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:02:55 INFO - 2016-04-19 19:02:55.651 screenresolution[2220:18435] starting screenresolution argv=screenresolution get 19:02:55 INFO - 2016-04-19 19:02:55.653 screenresolution[2220:18435] Display 0: 1600x1200x32@60 19:02:55 INFO - 2016-04-19 19:02:55.659 screenresolution[2221:18438] starting screenresolution argv=screenresolution list 19:02:55 INFO - Available Modes on Display 0 19:02:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:02:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:02:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:02:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:02:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:02:55 INFO - Intel Iris: 19:02:55 INFO - Chipset Model: Intel Iris 19:02:55 INFO - Type: GPU 19:02:55 INFO - Bus: Built-In 19:02:55 INFO - VRAM (Dynamic, Max): 1536 MB 19:02:55 INFO - Vendor: Intel (0x8086) 19:02:55 INFO - Device ID: 0x0a2e 19:02:55 INFO - Revision ID: 0x0009 19:02:55 INFO - Displays: 19:02:55 INFO - Display: 19:02:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:02:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:02:55 INFO - Display Serial Number: 1600x1200 60 19:02:55 INFO - Main Display: Yes 19:02:55 INFO - Mirror: Off 19:02:55 INFO - Online: Yes 19:02:55 INFO - Rotation: Supported 19:02:55 INFO - Return code: 0 19:02:55 INFO - Running post-action listener: _resource_record_post_action 19:02:55 INFO - Running post-run listener: _resource_record_post_run 19:02:56 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 327680; Write bytes: 695069184; Read time: 5; Write time: 2061 19:02:56 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 381606400; Write bytes: 375858688; Read time: 29147; Write time: 748 19:02:56 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 327680; Write bytes: 298218496; Read time: 5; Write time: 1261 19:02:56 INFO - Running post-run listener: _upload_blobber_files 19:02:56 INFO - Blob upload gear active. 19:02:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:02:56 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 19:02:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:02:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:02:57 INFO - (blobuploader) - INFO - Open directory for files ... 19:02:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:02:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:02:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:02:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:02:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:02:58 INFO - (blobuploader) - INFO - Done attempting. 19:02:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:02:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:02:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:03:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:03:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:03:00 INFO - (blobuploader) - INFO - Done attempting. 19:03:00 INFO - (blobuploader) - INFO - Iteration through files over. 19:03:00 INFO - Return code: 0 19:03:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:03:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:03:00 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bdabbcb7481789c39593357b948d1ec0d7f186170abaf9fcb4e9c494f9d546d864133c286cb9601e47154a882b71195b8ee8cf1bdb7b9a513d739e999170e973", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/82b0164152b21b640678d8cc78579834cc6bd21684a12a5ffaa2b8437254220afe26ad1bac80b79212035bc331483b89efeb17f71063a272ae123271bdab9978"} 19:03:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:03:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:03:00 INFO - Contents: 19:03:00 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bdabbcb7481789c39593357b948d1ec0d7f186170abaf9fcb4e9c494f9d546d864133c286cb9601e47154a882b71195b8ee8cf1bdb7b9a513d739e999170e973", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/82b0164152b21b640678d8cc78579834cc6bd21684a12a5ffaa2b8437254220afe26ad1bac80b79212035bc331483b89efeb17f71063a272ae123271bdab9978"} 19:03:00 INFO - Running post-run listener: copy_logs_to_upload_dir 19:03:00 INFO - Copying logs to upload dir... 19:03:00 INFO - mkdir: /builds/slave/test/build/upload/logs 19:03:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.556898 ========= master_lag: 164.71 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 23 mins, 56 secs) (at 2016-04-19 19:05:45.668833) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 2 secs) (at 2016-04-19 19:05:45.670034) ========= 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.ZA7AMP6JQL/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.6OivNVnza5/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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/Fx-Team/sha512/bdabbcb7481789c39593357b948d1ec0d7f186170abaf9fcb4e9c494f9d546d864133c286cb9601e47154a882b71195b8ee8cf1bdb7b9a513d739e999170e973", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/82b0164152b21b640678d8cc78579834cc6bd21684a12a5ffaa2b8437254220afe26ad1bac80b79212035bc331483b89efeb17f71063a272ae123271bdab9978"} build_url:https://queue.taskcluster.net/v1/task/WRjFB5zlT6iONjM4i02uLQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011547 build_url: 'https://queue.taskcluster.net/v1/task/WRjFB5zlT6iONjM4i02uLQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bdabbcb7481789c39593357b948d1ec0d7f186170abaf9fcb4e9c494f9d546d864133c286cb9601e47154a882b71195b8ee8cf1bdb7b9a513d739e999170e973", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/82b0164152b21b640678d8cc78579834cc6bd21684a12a5ffaa2b8437254220afe26ad1bac80b79212035bc331483b89efeb17f71063a272ae123271bdab9978"}' ========= master_lag: 2.73 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 2 secs) (at 2016-04-19 19:05:48.410332) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 19:05:48.413726) ========= 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.ZA7AMP6JQL/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.6OivNVnza5/Listeners TMPDIR=/var/folders/h0/9zkb9vkd5vjd65kp5941w0xh00000w/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.005113 ========= master_lag: 0.94 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 19:05:49.355192) ========= ========= Started reboot skipped (results: 3, elapsed: 2 mins, 9 secs) (at 2016-04-19 19:05:49.355478) ========= ========= Finished reboot skipped (results: 3, elapsed: 2 mins, 9 secs) (at 2016-04-19 19:07:58.498893) ========= ========= Total master_lag: 168.44 =========