builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0295 starttime: 1463434093.17 results: success (0) buildid: 20160516123747 builduid: 33eebac13c50467b92ab8327bd57bf9a revision: fd43bc66da8a9184f9aa7b8814393dec9b9e3be2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-16 14:28:13.173039) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-16 14:28:13.173440) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-16 14:28:13.173725) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-16 14:28:13.262628) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 14:28:13.262933) ========= 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.pl8FgYKsdO/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.LfNZnAbM66/Listeners TMPDIR=/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-16 14:28:13-- 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% 30.2M=0s 2016-05-16 14:28:13 (30.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.581191 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 14:28:13.865082) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 14:28:13.865370) ========= 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.pl8FgYKsdO/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.LfNZnAbM66/Listeners TMPDIR=/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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.025195 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 14:28:13.916418) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-16 14:28:13.919978) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev fd43bc66da8a9184f9aa7b8814393dec9b9e3be2 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev fd43bc66da8a9184f9aa7b8814393dec9b9e3be2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.pl8FgYKsdO/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.LfNZnAbM66/Listeners TMPDIR=/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-16 14:28:13,983 truncating revision to first 12 chars 2016-05-16 14:28:13,983 Setting DEBUG logging. 2016-05-16 14:28:13,983 attempt 1/10 2016-05-16 14:28:13,983 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/fd43bc66da8a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-16 14:28:14,717 unpacking tar archive at: mozilla-aurora-fd43bc66da8a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.992297 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-16 14:28:14.942629) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-16 14:28:14.942914) ========= script_repo_revision: fd43bc66da8a9184f9aa7b8814393dec9b9e3be2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-16 14:28:14.943286) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-16 14:28:14.943542) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-16 14:28:14.963432) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-05-16 14:28:14.963756) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.pl8FgYKsdO/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.LfNZnAbM66/Listeners TMPDIR=/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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 14:28:15 INFO - MultiFileLogger online at 20160516 14:28:15 in /builds/slave/test 14:28:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 14:28:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:28:15 INFO - {'append_to_log': False, 14:28:15 INFO - 'base_work_dir': '/builds/slave/test', 14:28:15 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 14:28:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:28:15 INFO - 'branch': 'Mozilla-Aurora', 14:28:15 INFO - 'buildbot_json_path': 'buildprops.json', 14:28:15 INFO - 'config_files': ('talos/mac_config.py',), 14:28:15 INFO - 'default_actions': ('clobber', 14:28:15 INFO - 'read-buildbot-config', 14:28:15 INFO - 'download-and-extract', 14:28:15 INFO - 'populate-webroot', 14:28:15 INFO - 'create-virtualenv', 14:28:15 INFO - 'install', 14:28:15 INFO - 'run-tests'), 14:28:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:28:15 INFO - 'download_minidump_stackwalk': True, 14:28:15 INFO - 'download_symbols': 'ondemand', 14:28:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:28:15 INFO - 'tooltool.py': '/tools/tooltool.py', 14:28:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:28:15 INFO - '/tools/misc-python/virtualenv.py')}, 14:28:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:28:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:28:15 INFO - 'installer_path': 'installer.exe', 14:28:15 INFO - 'log_level': 'info', 14:28:15 INFO - 'log_name': 'talos', 14:28:15 INFO - 'log_to_console': True, 14:28:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:28:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:28:15 INFO - 'opt_config_files': (), 14:28:15 INFO - 'pip_index': False, 14:28:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:28:15 INFO - 'cmd': ('bash', 14:28:15 INFO - '-c', 14:28:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:28:15 INFO - 'enabled': True, 14:28:15 INFO - 'halt_on_failure': False, 14:28:15 INFO - 'name': 'check_screen_resolution'},), 14:28:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:28:15 INFO - 'cmd': ('bash', 14:28:15 INFO - '-c', 14:28:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:28:15 INFO - 'enabled': True, 14:28:15 INFO - 'halt_on_failure': False, 14:28:15 INFO - 'name': 'check_screen_resolution'},), 14:28:15 INFO - 'run_cmd_checks_enabled': True, 14:28:15 INFO - 'sps_profile': False, 14:28:15 INFO - 'sps_profile_interval': 0, 14:28:15 INFO - 'suite': 'dromaeojs', 14:28:15 INFO - 'system_bits': '32', 14:28:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:28:15 INFO - 'title': 't-yosemite-r7-0295', 14:28:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:28:15 INFO - 'use_talos_json': True, 14:28:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:28:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:28:15 INFO - 'work_dir': 'build'} 14:28:15 INFO - ##### 14:28:15 INFO - ##### Running clobber step. 14:28:15 INFO - ##### 14:28:15 INFO - Running pre-action listener: _resource_record_pre_action 14:28:15 INFO - Running main action method: clobber 14:28:15 INFO - rmtree: /builds/slave/test/build 14:28:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:28:16 INFO - Running post-action listener: _resource_record_post_action 14:28:16 INFO - ##### 14:28:16 INFO - ##### Running read-buildbot-config step. 14:28:16 INFO - ##### 14:28:16 INFO - Running pre-action listener: _resource_record_pre_action 14:28:16 INFO - Running main action method: read_buildbot_config 14:28:16 INFO - Using buildbot properties: 14:28:16 INFO - { 14:28:16 INFO - "project": "", 14:28:16 INFO - "product": "firefox", 14:28:16 INFO - "script_repo_revision": "production", 14:28:16 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 14:28:16 INFO - "repository": "", 14:28:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 14:28:16 INFO - "buildid": "20160516123747", 14:28:16 INFO - "pgo_build": "False", 14:28:16 INFO - "basedir": "/builds/slave/test", 14:28:16 INFO - "buildnumber": 9, 14:28:16 INFO - "slavename": "t-yosemite-r7-0295", 14:28:16 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 14:28:16 INFO - "platform": "yosemite_r7", 14:28:16 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 14:28:16 INFO - "branch": "mozilla-aurora", 14:28:16 INFO - "repo_path": "releases/mozilla-aurora", 14:28:16 INFO - "revision": "fd43bc66da8a9184f9aa7b8814393dec9b9e3be2", 14:28:16 INFO - "stage_platform": "macosx64", 14:28:16 INFO - "builduid": "33eebac13c50467b92ab8327bd57bf9a", 14:28:16 INFO - "slavebuilddir": "test" 14:28:16 INFO - } 14:28:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/Q1LVbzKORf61r3aTB3ti-w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 14:28:16 INFO - Running post-action listener: _resource_record_post_action 14:28:16 INFO - ##### 14:28:16 INFO - ##### Running download-and-extract step. 14:28:16 INFO - ##### 14:28:16 INFO - Running pre-action listener: _resource_record_pre_action 14:28:16 INFO - Running main action method: download_and_extract 14:28:16 INFO - mkdir: /builds/slave/test/build/tests 14:28:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:28:16 INFO - https://queue.taskcluster.net/v1/task/Q1LVbzKORf61r3aTB3ti-w/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 14:28:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1LVbzKORf61r3aTB3ti-w/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 14:28:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1LVbzKORf61r3aTB3ti-w/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 14:28:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1LVbzKORf61r3aTB3ti-w/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 14:28:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1LVbzKORf61r3aTB3ti-w/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 14:28:27 INFO - Downloaded 1199 bytes. 14:28:27 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 14:28:27 INFO - Using the following test package requirements: 14:28:27 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 14:28:27 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 14:28:27 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 14:28:27 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 14:28:27 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 14:28:27 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 14:28:27 INFO - u'jsshell-mac.zip'], 14:28:27 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 14:28:27 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 14:28:27 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 14:28:27 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 14:28:27 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 14:28:27 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 14:28:27 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 14:28:27 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 14:28:27 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 14:28:27 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 14:28:27 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 14:28:27 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 14:28:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:28:27 INFO - https://queue.taskcluster.net/v1/task/Q1LVbzKORf61r3aTB3ti-w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 14:28:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1LVbzKORf61r3aTB3ti-w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 14:28:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1LVbzKORf61r3aTB3ti-w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 14:28:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1LVbzKORf61r3aTB3ti-w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 14:28:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1LVbzKORf61r3aTB3ti-w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 14:28:29 INFO - Downloaded 18102477 bytes. 14:28:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:28:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 14:28:32 INFO - Return code: 0 14:28:32 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 14:28:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:28:32 INFO - https://queue.taskcluster.net/v1/task/Q1LVbzKORf61r3aTB3ti-w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 14:28:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1LVbzKORf61r3aTB3ti-w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 14:28:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1LVbzKORf61r3aTB3ti-w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 14:28:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1LVbzKORf61r3aTB3ti-w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 14:28:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1LVbzKORf61r3aTB3ti-w/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 14:28:33 INFO - Downloaded 18102477 bytes. 14:28:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:28:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 14:28:37 INFO - Return code: 0 14:28:37 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')]} 14:28:37 INFO - https://queue.taskcluster.net/v1/task/Q1LVbzKORf61r3aTB3ti-w/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 14:28:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1LVbzKORf61r3aTB3ti-w/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 14:28:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1LVbzKORf61r3aTB3ti-w/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 14:28:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1LVbzKORf61r3aTB3ti-w/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 14:28:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1LVbzKORf61r3aTB3ti-w/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 14:28:39 INFO - Downloaded 10840145 bytes. 14:28:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:28:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 14:28:39 INFO - Return code: 0 14:28:39 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')]} 14:28:39 INFO - https://queue.taskcluster.net/v1/task/Q1LVbzKORf61r3aTB3ti-w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 14:28:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1LVbzKORf61r3aTB3ti-w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 14:28:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1LVbzKORf61r3aTB3ti-w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 14:28:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1LVbzKORf61r3aTB3ti-w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 14:28:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Q1LVbzKORf61r3aTB3ti-w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 14:28:43 INFO - Downloaded 114129159 bytes. 14:28:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Q1LVbzKORf61r3aTB3ti-w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 14:28:43 INFO - mkdir: /builds/slave/test/properties 14:28:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:28:43 INFO - Writing to file /builds/slave/test/properties/build_url 14:28:43 INFO - Contents: 14:28:43 INFO - build_url:https://queue.taskcluster.net/v1/task/Q1LVbzKORf61r3aTB3ti-w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 14:28:43 INFO - Running post-action listener: _resource_record_post_action 14:28:43 INFO - Running post-action listener: set_extra_try_arguments 14:28:43 INFO - ##### 14:28:43 INFO - ##### Running populate-webroot step. 14:28:43 INFO - ##### 14:28:43 INFO - Running pre-action listener: _resource_record_pre_action 14:28:43 INFO - Running main action method: populate_webroot 14:28:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:28:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:28:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:28:43 INFO - '--tppagecycles', 14:28:43 INFO - '7'], 14:28:43 INFO - 'tests': ['tsvgm']}}, 14:28:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:28:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:28:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:28:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:28:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:28:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:28:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:28:43 INFO - 'talos_options': ['--disable-e10s'], 14:28:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:28:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:28:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:28:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:28:43 INFO - 'talos_options': ['--disable-e10s'], 14:28:43 INFO - 'tests': ['damp', 'tps']}, 14:28:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:28:43 INFO - 'tests': ['damp', 'tps']}, 14:28:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:28:43 INFO - 'tests': ['dromaeo_dom']}, 14:28:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:28:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:28:43 INFO - 'tests': ['a11yr', 14:28:43 INFO - 'ts_paint', 14:28:43 INFO - 'tpaint', 14:28:43 INFO - 'sessionrestore', 14:28:43 INFO - 'sessionrestore_no_auto_restore', 14:28:43 INFO - 'tabpaint']}, 14:28:43 INFO - 'other-e10s': {'tests': ['a11yr', 14:28:43 INFO - 'ts_paint', 14:28:43 INFO - 'tpaint', 14:28:43 INFO - 'sessionrestore', 14:28:43 INFO - 'sessionrestore_no_auto_restore', 14:28:43 INFO - 'tabpaint']}, 14:28:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:28:43 INFO - 'ts_paint', 14:28:43 INFO - 'tpaint', 14:28:43 INFO - 'sessionrestore', 14:28:43 INFO - 'sessionrestore_no_auto_restore', 14:28:43 INFO - 'tabpaint']}, 14:28:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:28:43 INFO - 'ts_paint', 14:28:43 INFO - 'tpaint', 14:28:43 INFO - 'sessionrestore', 14:28:43 INFO - 'sessionrestore_no_auto_restore', 14:28:43 INFO - 'tabpaint']}, 14:28:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:28:43 INFO - 'tests': ['a11yr', 14:28:43 INFO - 'ts_paint', 14:28:43 INFO - 'tpaint', 14:28:43 INFO - 'sessionrestore', 14:28:43 INFO - 'sessionrestore_no_auto_restore', 14:28:43 INFO - 'tabpaint']}, 14:28:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:28:43 INFO - 'tests': ['a11yr', 14:28:43 INFO - 'ts_paint', 14:28:43 INFO - 'tpaint', 14:28:43 INFO - 'sessionrestore', 14:28:43 INFO - 'sessionrestore_no_auto_restore', 14:28:43 INFO - 'tabpaint']}, 14:28:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:28:43 INFO - 'tests': ['tsvgx', 14:28:43 INFO - 'tsvgr_opacity', 14:28:43 INFO - 'tart', 14:28:43 INFO - 'tscrollx', 14:28:43 INFO - 'cart']}, 14:28:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:28:43 INFO - 'tsvgr_opacity', 14:28:43 INFO - 'tart', 14:28:43 INFO - 'tscrollx', 14:28:43 INFO - 'cart']}, 14:28:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:28:43 INFO - 'talos_options': ['--disable-e10s'], 14:28:43 INFO - 'tests': ['tp5o']}, 14:28:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:28:43 INFO - 'tests': ['tp5o']}, 14:28:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:28:43 INFO - 'talos_options': ['--disable-e10s', 14:28:43 INFO - '--xperf_path', 14:28:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:28:43 INFO - 'tests': ['tp5n']}, 14:28:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:28:43 INFO - 'talos_options': ['--xperf_path', 14:28:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:28:43 INFO - 'tests': ['tp5n']}}, 14:28:43 INFO - 'talos.zip': {'path': '', 14:28:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:28:43 INFO - Running post-action listener: _resource_record_post_action 14:28:43 INFO - ##### 14:28:43 INFO - ##### Running create-virtualenv step. 14:28:43 INFO - ##### 14:28:43 INFO - Running pre-action listener: _resource_record_pre_action 14:28:43 INFO - Running main action method: create_virtualenv 14:28:43 INFO - Creating virtualenv /builds/slave/test/build/venv 14:28:43 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 14:28:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:28:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:28:44 INFO - Using real prefix '/tools/python27' 14:28:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:28:44 INFO - Installing distribute.............................................................................................................................................................................................done. 14:28:47 INFO - Installing pip.................done. 14:28:47 INFO - Return code: 0 14:28:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:28:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:28:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:28:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:28:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:28:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:28:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:28:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106f9fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106faf420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89715814c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e3ee00>, '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 0x106ca7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e39b20>, '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.pl8FgYKsdO/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.LfNZnAbM66/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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 14:28:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:28:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:28:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pl8FgYKsdO/Render', 14:28:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:28:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:28:47 INFO - 'HOME': '/Users/cltbld', 14:28:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:28:47 INFO - 'LOGNAME': 'cltbld', 14:28:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:28:47 INFO - 'MOZ_NO_REMOTE': '1', 14:28:47 INFO - 'NO_EM_RESTART': '1', 14:28:47 INFO - 'PAGER': '/bin/cat', 14:28:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:28:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:28:47 INFO - 'PWD': '/builds/slave/test', 14:28:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:28:47 INFO - 'SHELL': '/bin/bash', 14:28:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LfNZnAbM66/Listeners', 14:28:47 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 14:28:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:28:47 INFO - 'USER': 'cltbld', 14:28:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:28:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:28:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:28:47 INFO - 'XPC_FLAGS': '0x0', 14:28:47 INFO - 'XPC_SERVICE_NAME': '0', 14:28:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:28:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:28:47 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 14:28:47 INFO - Cleaning up... 14:28:47 INFO - Return code: 0 14:28:47 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 14:28:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:28:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:28:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:28:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:28:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:28:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:28:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106f9fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106faf420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89715814c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e3ee00>, '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 0x106ca7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e39b20>, '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.pl8FgYKsdO/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.LfNZnAbM66/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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 14:28:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 14:28:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 14:28:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pl8FgYKsdO/Render', 14:28:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:28:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:28:47 INFO - 'HOME': '/Users/cltbld', 14:28:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:28:47 INFO - 'LOGNAME': 'cltbld', 14:28:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:28:47 INFO - 'MOZ_NO_REMOTE': '1', 14:28:47 INFO - 'NO_EM_RESTART': '1', 14:28:47 INFO - 'PAGER': '/bin/cat', 14:28:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:28:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:28:47 INFO - 'PWD': '/builds/slave/test', 14:28:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:28:47 INFO - 'SHELL': '/bin/bash', 14:28:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LfNZnAbM66/Listeners', 14:28:47 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 14:28:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:28:47 INFO - 'USER': 'cltbld', 14:28:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:28:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:28:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:28:47 INFO - 'XPC_FLAGS': '0x0', 14:28:47 INFO - 'XPC_SERVICE_NAME': '0', 14:28:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:28:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:28:48 INFO - Downloading/unpacking psutil>=0.7.1 14:28:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:28:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:28:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:28:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:28:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:28:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:28:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:28:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:28:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:28:51 INFO - Installing collected packages: psutil 14:28:51 INFO - Running setup.py install for psutil 14:28:51 INFO - building 'psutil._psutil_osx' extension 14:28:51 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 14:28:51 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 14:28:51 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 14:28:51 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 14:28:51 INFO - building 'psutil._psutil_posix' extension 14:28:51 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 14:28:52 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:28:52 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:28:52 INFO - ^ 14:28:52 INFO - 1 warning generated. 14:28:52 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 14:28:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:28:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:28:52 INFO - Successfully installed psutil 14:28:52 INFO - Cleaning up... 14:28:52 INFO - Return code: 0 14:28:52 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 14:28: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')]} 14:28:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:28:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:28: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')]} 14:28:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:28:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:28:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106f9fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106faf420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89715814c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e3ee00>, '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 0x106ca7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e39b20>, '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.pl8FgYKsdO/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.LfNZnAbM66/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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 14:28:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 14:28:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 14:28:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pl8FgYKsdO/Render', 14:28:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:28:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:28:52 INFO - 'HOME': '/Users/cltbld', 14:28:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:28:52 INFO - 'LOGNAME': 'cltbld', 14:28:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:28:52 INFO - 'MOZ_NO_REMOTE': '1', 14:28:52 INFO - 'NO_EM_RESTART': '1', 14:28:52 INFO - 'PAGER': '/bin/cat', 14:28:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:28:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:28:52 INFO - 'PWD': '/builds/slave/test', 14:28:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:28:52 INFO - 'SHELL': '/bin/bash', 14:28:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LfNZnAbM66/Listeners', 14:28:52 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 14:28:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:28:52 INFO - 'USER': 'cltbld', 14:28:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:28:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:28:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:28:52 INFO - 'XPC_FLAGS': '0x0', 14:28:52 INFO - 'XPC_SERVICE_NAME': '0', 14:28:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:28:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:28:52 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 14:28:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:28:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:28:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:28:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:28:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:28:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:28:54 INFO - Downloading mozsystemmonitor-0.0.tar.gz 14:28:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:28:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 14:28:55 INFO - Installing collected packages: mozsystemmonitor 14:28:55 INFO - Running setup.py install for mozsystemmonitor 14:28:55 INFO - Successfully installed mozsystemmonitor 14:28:55 INFO - Cleaning up... 14:28:55 INFO - Return code: 0 14:28:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:28:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:28:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:28:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:28:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:28:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:28:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:28:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106f9fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106faf420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89715814c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e3ee00>, '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 0x106ca7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e39b20>, '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.pl8FgYKsdO/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.LfNZnAbM66/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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 14:28:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:28:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:28:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pl8FgYKsdO/Render', 14:28:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:28:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:28:55 INFO - 'HOME': '/Users/cltbld', 14:28:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:28:55 INFO - 'LOGNAME': 'cltbld', 14:28:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:28:55 INFO - 'MOZ_NO_REMOTE': '1', 14:28:55 INFO - 'NO_EM_RESTART': '1', 14:28:55 INFO - 'PAGER': '/bin/cat', 14:28:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:28:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:28:55 INFO - 'PWD': '/builds/slave/test', 14:28:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:28:55 INFO - 'SHELL': '/bin/bash', 14:28:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LfNZnAbM66/Listeners', 14:28:55 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 14:28:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:28:55 INFO - 'USER': 'cltbld', 14:28:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:28:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:28:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:28:55 INFO - 'XPC_FLAGS': '0x0', 14:28:55 INFO - 'XPC_SERVICE_NAME': '0', 14:28:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:28:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:28:55 INFO - Downloading/unpacking blobuploader==1.2.4 14:28:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:28:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:28:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:28:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:28:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:28:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:28:58 INFO - Downloading blobuploader-1.2.4.tar.gz 14:28:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:28:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:28:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:28:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:28:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:28:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:28:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:28:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:28:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:28:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:28:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:28:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:28:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:28:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:28:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:28:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:28:59 INFO - Downloading docopt-0.6.1.tar.gz 14:28:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:28:59 INFO - Installing collected packages: blobuploader, requests, docopt 14:28:59 INFO - Running setup.py install for blobuploader 14:28:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:28:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:28:59 INFO - Running setup.py install for requests 14:29:00 INFO - Running setup.py install for docopt 14:29:00 INFO - Successfully installed blobuploader requests docopt 14:29:00 INFO - Cleaning up... 14:29:00 INFO - Return code: 0 14:29:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:29:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:29:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:29:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:29:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:29:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106f9fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106faf420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89715814c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e3ee00>, '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 0x106ca7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e39b20>, '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.pl8FgYKsdO/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.LfNZnAbM66/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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 14:29:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:29:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:29:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pl8FgYKsdO/Render', 14:29:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:29:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:29:00 INFO - 'HOME': '/Users/cltbld', 14:29:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:29:00 INFO - 'LOGNAME': 'cltbld', 14:29:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:00 INFO - 'MOZ_NO_REMOTE': '1', 14:29:00 INFO - 'NO_EM_RESTART': '1', 14:29:00 INFO - 'PAGER': '/bin/cat', 14:29:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:29:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:00 INFO - 'PWD': '/builds/slave/test', 14:29:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:29:00 INFO - 'SHELL': '/bin/bash', 14:29:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LfNZnAbM66/Listeners', 14:29:00 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 14:29:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:29:00 INFO - 'USER': 'cltbld', 14:29:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:29:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:29:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:00 INFO - 'XPC_FLAGS': '0x0', 14:29:00 INFO - 'XPC_SERVICE_NAME': '0', 14:29:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:29:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:29:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:29:00 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-jT_KPC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:29:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:29:00 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-D3NDo_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:29:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:29:00 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-4oPlZe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:29:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:29:00 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-jcbycD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:29:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:29:00 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-sv6SEY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:29:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:29:01 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-a22oV6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:29:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:29:01 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-BZojA9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:29:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:29:01 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-WLegW3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:29:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:29:01 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-1EHBRx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:29:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:29:01 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-kiFFmS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:29:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:29:01 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-N5Ywcm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:29:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:29:01 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-oFbFQG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:29:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:29:01 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-IvyXIp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:29:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:29:01 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-gtDCP1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:29:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:29:01 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-SVRdy1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:29:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:29:02 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-jaYeZW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:29:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:29:02 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-vCnqlv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:29:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:29:02 INFO - Running setup.py install for manifestparser 14:29:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:29:02 INFO - Running setup.py install for mozcrash 14:29:02 INFO - Running setup.py install for mozdebug 14:29:02 INFO - Running setup.py install for mozdevice 14:29:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:29:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:29:02 INFO - Running setup.py install for mozfile 14:29:03 INFO - Running setup.py install for mozhttpd 14:29:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:29:03 INFO - Running setup.py install for mozinfo 14:29:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:29:03 INFO - Running setup.py install for mozInstall 14:29:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:29:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:29:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:29:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:29:03 INFO - Running setup.py install for mozleak 14:29:03 INFO - Running setup.py install for mozlog 14:29:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:29:03 INFO - Running setup.py install for moznetwork 14:29:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:29:03 INFO - Running setup.py install for mozprocess 14:29:04 INFO - Running setup.py install for mozprofile 14:29:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:29:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:29:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:29:04 INFO - Running setup.py install for mozrunner 14:29:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:29:04 INFO - Running setup.py install for mozscreenshot 14:29:04 INFO - Running setup.py install for moztest 14:29:04 INFO - Running setup.py install for mozversion 14:29:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:29:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:29:04 INFO - Cleaning up... 14:29:04 INFO - Return code: 0 14:29:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:29: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')]} 14:29:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:29:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29: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')]} 14:29:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:29:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:04 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 0x106f9fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106faf420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89715814c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e3ee00>, '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 0x106ca7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e39b20>, '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.pl8FgYKsdO/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.LfNZnAbM66/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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 14:29:04 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 14:29:04 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 14:29:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pl8FgYKsdO/Render', 14:29:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:29:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:29:04 INFO - 'HOME': '/Users/cltbld', 14:29:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:29:04 INFO - 'LOGNAME': 'cltbld', 14:29:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:04 INFO - 'MOZ_NO_REMOTE': '1', 14:29:04 INFO - 'NO_EM_RESTART': '1', 14:29:04 INFO - 'PAGER': '/bin/cat', 14:29:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:29:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:04 INFO - 'PWD': '/builds/slave/test', 14:29:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:29:04 INFO - 'SHELL': '/bin/bash', 14:29:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LfNZnAbM66/Listeners', 14:29:04 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 14:29:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:29:04 INFO - 'USER': 'cltbld', 14:29:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:29:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:29:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:04 INFO - 'XPC_FLAGS': '0x0', 14:29:04 INFO - 'XPC_SERVICE_NAME': '0', 14:29:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:29:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:29:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:29:05 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-LuIe0I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:29:05 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 14:29:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:29:05 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-YDzzRc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:29:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:29:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:29:05 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-w2DDVu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:29:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:29:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:29:05 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-esPiXD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:29:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:29:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:29:05 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-bZ9gqv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:29:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 14:29:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:29:05 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-V9Bxni-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:29:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 14:29:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:29:05 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-5YfhLF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:29:05 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)) 14:29:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:29:05 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-GjlYXW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:29:05 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)) 14:29:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:29:05 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-7rHADx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:29:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 14:29:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:29:06 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-YYT7Wp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:29:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:29:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:29:06 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-KNlA8H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:29:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 14:29:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:29:06 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-phrykm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:29:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 14:29:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:29:06 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-MaqAMB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:29:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 14:29:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:29:06 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-g0E6wA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:29:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 14:29:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:29:06 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-lf1j26-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:29:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 14:29:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:29:06 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-cXolrr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:29:06 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)) 14:29:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:29:06 INFO - Running setup.py (path:/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/pip-c1sr6W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:29:06 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)) 14:29:06 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)) 14:29:06 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)) 14:29:06 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)) 14:29:06 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)) 14:29:06 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)) 14:29:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:29:06 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 14:29:06 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 14:29:06 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 14:29:06 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 14:29:06 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 14:29:06 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 14:29:09 INFO - Downloading blessings-1.6.tar.gz 14:29:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:29:09 INFO - Installing collected packages: blessings 14:29:09 INFO - Running setup.py install for blessings 14:29:10 INFO - Successfully installed blessings 14:29:10 INFO - Cleaning up... 14:29:10 INFO - Return code: 0 14:29:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:29:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:29:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:29:10 INFO - Reading from file tmpfile_stdout 14:29:10 INFO - Current package versions: 14:29:10 INFO - blessings == 1.6 14:29:10 INFO - blobuploader == 1.2.4 14:29:10 INFO - docopt == 0.6.1 14:29:10 INFO - manifestparser == 1.1 14:29:10 INFO - mozInstall == 1.12 14:29:10 INFO - mozcrash == 0.17 14:29:10 INFO - mozdebug == 0.1 14:29:10 INFO - mozdevice == 0.48 14:29:10 INFO - mozfile == 1.2 14:29:10 INFO - mozhttpd == 0.7 14:29:10 INFO - mozinfo == 0.9 14:29:10 INFO - mozleak == 0.1 14:29:10 INFO - mozlog == 3.1 14:29:10 INFO - moznetwork == 0.27 14:29:10 INFO - mozprocess == 0.22 14:29:10 INFO - mozprofile == 0.28 14:29:10 INFO - mozrunner == 6.11 14:29:10 INFO - mozscreenshot == 0.1 14:29:10 INFO - mozsystemmonitor == 0.0 14:29:10 INFO - moztest == 0.7 14:29:10 INFO - mozversion == 1.4 14:29:10 INFO - psutil == 3.1.1 14:29:10 INFO - requests == 1.2.3 14:29:10 INFO - wsgiref == 0.1.2 14:29:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:29:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:29:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:29:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:29:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:29:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106f9fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106faf420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89715814c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e3ee00>, '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 0x106ca7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e39b20>, '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.pl8FgYKsdO/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.LfNZnAbM66/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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 14:29:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 14:29:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:29:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pl8FgYKsdO/Render', 14:29:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:29:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:29:10 INFO - 'HOME': '/Users/cltbld', 14:29:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:29:10 INFO - 'LOGNAME': 'cltbld', 14:29:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:10 INFO - 'MOZ_NO_REMOTE': '1', 14:29:10 INFO - 'NO_EM_RESTART': '1', 14:29:10 INFO - 'PAGER': '/bin/cat', 14:29:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:29:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:10 INFO - 'PWD': '/builds/slave/test', 14:29:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:29:10 INFO - 'SHELL': '/bin/bash', 14:29:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LfNZnAbM66/Listeners', 14:29:10 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 14:29:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:29:10 INFO - 'USER': 'cltbld', 14:29:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:29:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:29:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:10 INFO - 'XPC_FLAGS': '0x0', 14:29:10 INFO - 'XPC_SERVICE_NAME': '0', 14:29:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:29:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:29:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:29:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 14:29:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 14:29:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:29:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 14:29:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 14:29:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 14:29:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 14:29:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 14:29:10 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:29:10 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:29:10 INFO - Cleaning up... 14:29:10 INFO - Return code: 0 14:29:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:29:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:29:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:29:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:29:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:29:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106f9fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106faf420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89715814c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e3ee00>, '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 0x106ca7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e39b20>, '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.pl8FgYKsdO/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.LfNZnAbM66/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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 14:29:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:29:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:29:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pl8FgYKsdO/Render', 14:29:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:29:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:29:10 INFO - 'HOME': '/Users/cltbld', 14:29:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:29:10 INFO - 'LOGNAME': 'cltbld', 14:29:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:10 INFO - 'MOZ_NO_REMOTE': '1', 14:29:10 INFO - 'NO_EM_RESTART': '1', 14:29:10 INFO - 'PAGER': '/bin/cat', 14:29:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:29:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:10 INFO - 'PWD': '/builds/slave/test', 14:29:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:29:10 INFO - 'SHELL': '/bin/bash', 14:29:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LfNZnAbM66/Listeners', 14:29:10 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 14:29:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:29:10 INFO - 'USER': 'cltbld', 14:29:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:29:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:29:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:10 INFO - 'XPC_FLAGS': '0x0', 14:29:10 INFO - 'XPC_SERVICE_NAME': '0', 14:29:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:29:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:29:10 INFO - Downloading/unpacking jsonschema 14:29:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:29:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:29:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:29:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:29:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:29:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:29:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:29:13 INFO - Downloading/unpacking functools32 (from jsonschema) 14:29:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:29:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:29:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:29:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:29:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:29:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:29:14 INFO - Downloading functools32-3.2.3-2.tar.gz 14:29:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:29:14 INFO - warning: no files found matching '*.txt' 14:29:14 INFO - no previously-included directories found matching 'build' 14:29:14 INFO - no previously-included directories found matching 'dist' 14:29:14 INFO - no previously-included directories found matching '.git*' 14:29:14 INFO - Installing collected packages: jsonschema, functools32 14:29:14 INFO - Running setup.py install for functools32 14:29:14 INFO - warning: no files found matching '*.txt' 14:29:14 INFO - no previously-included directories found matching 'build' 14:29:14 INFO - no previously-included directories found matching 'dist' 14:29:14 INFO - no previously-included directories found matching '.git*' 14:29:14 INFO - Successfully installed jsonschema functools32 14:29:14 INFO - Cleaning up... 14:29:14 INFO - Return code: 0 14:29:14 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 14:29:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:29:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:29:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:29:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:29:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:29:14 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 0x106f9fa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106faf420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89715814c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e3ee00>, '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 0x106ca7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e39b20>, '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.pl8FgYKsdO/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.LfNZnAbM66/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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 14:29:14 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 14:29:14 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 14:29:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pl8FgYKsdO/Render', 14:29:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:29:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:29:14 INFO - 'HOME': '/Users/cltbld', 14:29:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:29:14 INFO - 'LOGNAME': 'cltbld', 14:29:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:14 INFO - 'MOZ_NO_REMOTE': '1', 14:29:14 INFO - 'NO_EM_RESTART': '1', 14:29:14 INFO - 'PAGER': '/bin/cat', 14:29:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:29:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:14 INFO - 'PWD': '/builds/slave/test', 14:29:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:29:14 INFO - 'SHELL': '/bin/bash', 14:29:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LfNZnAbM66/Listeners', 14:29:14 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 14:29:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:29:14 INFO - 'USER': 'cltbld', 14:29:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:29:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:29:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:14 INFO - 'XPC_FLAGS': '0x0', 14:29:14 INFO - 'XPC_SERVICE_NAME': '0', 14:29:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:29:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:29:14 INFO - Downloading/unpacking flake8 14:29:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:29:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:29:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:29:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:29:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:29:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:29:17 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 14:29:17 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 14:29: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 14:29: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 14:29: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 14:29: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 14:29: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 14:29: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 14:29:17 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 14:29:17 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 14:29: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 14:29: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 14:29: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 14:29: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 14:29: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 14:29: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 14:29:17 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 14:29: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 14:29: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 14:29: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 14:29: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 14:29: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 14:29: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 14:29:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 14:29:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:29:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:29:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:29:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:29:18 INFO - no previously-included directories found matching 'docs/_build' 14:29:18 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 14:29:18 INFO - Running setup.py install for pep8 14:29:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:29:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:29:18 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:29:18 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:29:18 INFO - no previously-included directories found matching 'docs/_build' 14:29:18 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 14:29:18 INFO - Successfully installed flake8 mccabe pyflakes pep8 14:29:18 INFO - Cleaning up... 14:29:18 INFO - Return code: 0 14:29:18 INFO - Running post-action listener: _resource_record_post_action 14:29:18 INFO - Running post-action listener: _start_resource_monitoring 14:29:18 INFO - Starting resource monitoring. 14:29:18 INFO - ##### 14:29:18 INFO - ##### Running install step. 14:29:18 INFO - ##### 14:29:18 INFO - Running pre-action listener: _resource_record_pre_action 14:29:18 INFO - Running main action method: install 14:29:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:29:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:29:19 INFO - Reading from file tmpfile_stdout 14:29:19 INFO - Detecting whether we're running mozinstall >=1.0... 14:29:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:29:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:29:19 INFO - Reading from file tmpfile_stdout 14:29:19 INFO - Output received: 14:29:19 INFO - Usage: mozinstall [options] installer 14:29:19 INFO - Options: 14:29:19 INFO - -h, --help show this help message and exit 14:29:19 INFO - -d DEST, --destination=DEST 14:29:19 INFO - Directory to install application into. [default: 14:29:19 INFO - "/builds/slave/test"] 14:29:19 INFO - --app=APP Application being installed. [default: firefox] 14:29:19 INFO - mkdir: /builds/slave/test/build/application 14:29:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 14:29:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 14:29:50 INFO - Reading from file tmpfile_stdout 14:29:50 INFO - Output received: 14:29:50 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 14:29:50 INFO - Running post-action listener: _resource_record_post_action 14:29:50 INFO - ##### 14:29:50 INFO - ##### Running run-tests step. 14:29:50 INFO - ##### 14:29:50 INFO - Running pre-action listener: _resource_record_pre_action 14:29:50 INFO - Running main action method: run_tests 14:29:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:29:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:29:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:29:50 INFO - 2016-05-16 14:29:50.633 screenresolution[1653:8588] starting screenresolution argv=screenresolution get 14:29:50 INFO - 2016-05-16 14:29:50.636 screenresolution[1653:8588] Display 0: 1600x1200x32@60 14:29:50 INFO - 2016-05-16 14:29:50.642 screenresolution[1654:8591] starting screenresolution argv=screenresolution list 14:29:50 INFO - Available Modes on Display 0 14:29:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:29:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:29:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:29:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:29:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:29:50 INFO - Intel Iris: 14:29:50 INFO - Chipset Model: Intel Iris 14:29:50 INFO - Type: GPU 14:29:50 INFO - Bus: Built-In 14:29:50 INFO - VRAM (Dynamic, Max): 1536 MB 14:29:50 INFO - Vendor: Intel (0x8086) 14:29:50 INFO - Device ID: 0x0a2e 14:29:50 INFO - Revision ID: 0x0009 14:29:50 INFO - Displays: 14:29:50 INFO - Display: 14:29:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:29:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:29:50 INFO - Display Serial Number: 1600x1200 60 14:29:50 INFO - Main Display: Yes 14:29:50 INFO - Mirror: Off 14:29:50 INFO - Online: Yes 14:29:50 INFO - Rotation: Supported 14:29:50 INFO - Return code: 0 14:29:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:29:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:29:50 INFO - Python 2.7.3 14:29:50 INFO - Return code: 0 14:29:50 INFO - grabbing minidump binary from tooltool 14:29:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:29:50 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e3ee00>, '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 0x106ca7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e39b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:29:50 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 14:29:50 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 14:29:50 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:29:50 INFO - Return code: 0 14:29:50 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:29:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:29:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:29:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:29:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:29:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 14:29:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 14:29:52 INFO - Return code: 0 14:29:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Q1LVbzKORf61r3aTB3ti-w/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0295', '--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 14:29:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Q1LVbzKORf61r3aTB3ti-w/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0295 --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 14:29:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pl8FgYKsdO/Render', 14:29:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:29:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:29:52 INFO - 'HOME': '/Users/cltbld', 14:29:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:29:52 INFO - 'LOGNAME': 'cltbld', 14:29:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:29:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:29:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:29:52 INFO - 'MOZ_NO_REMOTE': '1', 14:29:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:29:52 INFO - 'NO_EM_RESTART': '1', 14:29:52 INFO - 'PAGER': '/bin/cat', 14:29:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:29:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:29:52 INFO - 'PWD': '/builds/slave/test', 14:29:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:29:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:29:52 INFO - 'SHELL': '/bin/bash', 14:29:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LfNZnAbM66/Listeners', 14:29:52 INFO - 'TMPDIR': '/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/', 14:29:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:29:52 INFO - 'USER': 'cltbld', 14:29:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:29:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:29:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:29:52 INFO - 'XPC_FLAGS': '0x0', 14:29:52 INFO - 'XPC_SERVICE_NAME': '0', 14:29:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:29:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Q1LVbzKORf61r3aTB3ti-w/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0295', '--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 14:29:52 INFO - mozversion application_buildid: 20160516123747 14:29:52 INFO - mozversion application_changeset: fd43bc66da8a9184f9aa7b8814393dec9b9e3be2 14:29:52 INFO - mozversion application_display_name: FirefoxDeveloperEdition 14:29:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:29:52 INFO - mozversion application_name: Firefox 14:29:52 INFO - mozversion application_remotingname: firefox-dev 14:29:52 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:29:52 INFO - mozversion application_vendor: Mozilla 14:29:52 INFO - mozversion application_version: 48.0a2 14:29:52 INFO - mozversion platform_buildid: 20160516123747 14:29:52 INFO - mozversion platform_changeset: fd43bc66da8a9184f9aa7b8814393dec9b9e3be2 14:29:52 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:29:52 INFO - mozversion platform_version: 48.0a2 14:29:52 INFO - using testdate: 1463434192 14:29:52 INFO - actual date: 1463434192 14:29:52 INFO - starting webserver on 'localhost:49280' 14:29:52 INFO - SUITE-START | Running 2 tests 14:29:52 INFO - TEST-START | dromaeo_css 14:29:52 INFO - operating with platform_type : mac_ 14:29:52 INFO - Initialising browser for dromaeo_css test... 14:29:52 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/tmpDgBskH/profile http://localhost:49280/getInfo.html) 14:29:54 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 14:29:54 INFO - PROCESS | 1672 | colorDepth:24 14:29:54 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 14:29:54 INFO - PROCESS | 1672 | __metrics 14:29:54 INFO - PROCESS | 1672 | 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" 14:29:54 ERROR - PROCESS | 1672 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 14:29:55 INFO - TEST-INFO | 1672: exit 0 14:29:55 INFO - Browser initialized. 14:29:55 INFO - Running cycle 1/1 for dromaeo_css test... 14:29:55 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/tmpDgBskH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:30:07 INFO - PROCESS | 1675 | RSS: Main: 211136512 14:30:07 INFO - PROCESS | 1675 | 14:30:58 INFO - PROCESS | 1675 | 1463434258868 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] 14:33:09 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49280/tests/dromaeo/cssquery-ext.html) 14:33:11 INFO - PROCESS | 1675 | RSS: Main: 328781824 14:33:11 INFO - PROCESS | 1675 | 14:36:13 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-ext.html (next: http://localhost:49280/tests/dromaeo/cssquery-jquery.html) 14:36:14 INFO - PROCESS | 1675 | RSS: Main: 353546240 14:36:14 INFO - PROCESS | 1675 | 14:39:16 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49280/tests/dromaeo/cssquery-mootools.html) 14:39:17 INFO - PROCESS | 1675 | RSS: Main: 354500608 14:39:17 INFO - PROCESS | 1675 | 14:42:19 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49280/tests/dromaeo/cssquery-prototype.html) 14:42:20 INFO - PROCESS | 1675 | RSS: Main: 355635200 14:42:20 INFO - PROCESS | 1675 | 14:45:23 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49280/tests/dromaeo/cssquery-yui.html) 14:45:24 INFO - PROCESS | 1675 | RSS: Main: 331554816 14:45:24 INFO - PROCESS | 1675 | 14:48:26 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-yui.html (next: http://localhost:49280/tests/dromaeo/cssquery-dojo.html) 14:48:27 INFO - PROCESS | 1675 | RSS: Main: 368586752 14:48:27 INFO - PROCESS | 1675 | 14:48:27 INFO - PROCESS | 1675 | __start_tp_report 14:48:27 INFO - PROCESS | 1675 | _x_x_mozilla_page_load 14:48:27 INFO - PROCESS | 1675 | _x_x_mozilla_page_load_details 14:48:27 INFO - PROCESS | 1675 | |i|pagename|runs| 14:48:27 INFO - PROCESS | 1675 | |0;dojo.html;3329.6337402885683;3389.0510657016043;3419.555457790487;3428.40002999475;3489.3370259650665;5657.717114144297;5668.943310566893;5676.432356764321;5680.375158732536;5726.3700992890745;5616.887662246752;5656.858578535539;5665.886682266352;5667.121596152603;5668.829935101953;5023.974880125613;5110.412302585213;5154.252633368159;5196.974015129919;5215.713135777531;5164.431912489623;5185.559227465651;5199.714015729133;5206.010857936999;5213.947860521384;5140.177571588547;5180.818671346504;5185.144451165553;5214.687118772867;5236.3978142513715;5211.1922651989;5224.712640804754;5225.320708307933;5239;5274.973625131888;4927.1870141426625;4990.451050384455;5075.949240507584;5147.742612869341;5165;5474.671519708791;5479.506844383985;5529.391766905637;5539.861503462445;5581.079121944874;6104.908426373563;6169.81490555284;6184.412480814315;6185.288691800455;6193.2877719062435;5127.333446651912;6052.050818140233;6149.108379285023;6211.844703882394;6273.749050037993;5756.30924289088;5773.826785196451;5779.855503612359;5802.274715660543;5810.767569297223;6272.8118156455375;6274.843128921813;6277.968610157011;6280.497560195174;6292.119103325538;5102.2346648003095;5985.311689155716;6092.055731361646;6108.205933228652;6121.204243448323;6251.687415629201;6274.2784579773;6279.3720627937755;6279.403456671609;6280.9685951569945;6156.476699480594;6179.536534759911;6191.938080619227;6196.845078872974;6203.751849925952;6038.63768173911;6133.977404287646;6144.109104179869;6144.986077297196;6233.812985610439;4796;4834.226523756252;4840.2255639097575;4863.367762190893;4903.485134060944;4375.759333236639;4387.144506821203;4393.912121757547;4400.141972315432;4426.247537918497;4881.755912204447;5540.63985840919;5569.275994120739;5630.493255607015;5633.408492108351;6089.756409743649;6129.450483694044;6134.5092392608485;6139.9828168395225;6163.3836616338;5576.442355764472;5582.720863956786;5602.075657516546;5605.971970140205;5612.719364031782;5246.449122842047;5304.681719096867;5306.814261500829;5309.522143007148;5311.93761247752;6201.162843016135;6212.875742485216;6263.843403914848;6286.371362863677;6286.937130628727;5713.742976545153;6096.116063170904;6148.723307451265;6251.6874156292915;6260.843478913063;6254.937450625435;6275.571630539642;6275.874482510416;6285.151504546828;6288.056791481132;5956.315023772322;6125.571210015256;6138.232720909887;6163.537734669828;6173.938260617337;6112.5946701277735;6148.44663980244;6208.565400422108;6236.158118654105;6243.8126856194385;5972.014617588223;6039.396060393926;6054.3642917493025;6092.116643086816;6106.202605869653;4337.414449049338;5823;5951.196588460502;5960.105984102419;5977.790777322773;5718.1136923776885;5833.125031245348;5853.560982926212;5914.881702366015;5921.052631578927;6004;6010.338862725184;6019.578629495893;6061.393860613904;6063.605865618721;5723.6851973141875;5806.1000544915605;5819.301683797971;5839.591228613956;5869.8239052828485 14:48:27 INFO - PROCESS | 1675 | |1;ext.html;544.3060098374574;548.5967813656963;548.9121740521515;591.2874985642302;634.8825467288552;31739.68260317399;32319.838400807992;32489.862854800085;39649.60350396495;40440.191196176056;42682;43342;43358.349624755654;43359;43444.565554344445;9406.529673516314;9589.66436174734;9589.808203835919;9599.568019439143;9615.326927115104;8552.828943421127;8745.650173993032;9345.579448924815;11456.942715286412;11663.41682915855;11380.943095284512;11481.942590287037;11484.310941343505;11496.137789665767;11541.826872596876;8552.27305679018;11305.717357066098;11414.600488982887;11417.31496110236;11424.657260282205;11376.601818936297;11587.710307242301;11593.478293476814;11598.478068486855;11611;38288.42567361492;39760.20479590392;40158.59841401578;40244.597554024374;40283.57589604858;3183.570218020561;3236.2718388362664;3337.4826901830256;3423.1613254752747;3508.8070156141657;28256.43487130246;29189;29519.55720664192;29562.40875182484;29702.85148574265;2751.0508874438287;2760.647282831418;2761.8066735328534;2785.1366076516347;2815.6480439945008;358017;359674;360385;493189;495190.52404738107;63838;64179.35820641827;64266;64348;64663.67668161723;57885.421145788845;72308.63845680738;72483.91274130892;72614.63692681503;72643.63678181576;15096.698066038618;17463.77753557264;17709.557261068578;17714.379996700052;17758.644827103384;12548.68628284282;12764.744705106032;13381.53164639233;13459.461621535225;13483.528076517276;20079.096440660207;20606.27878024292;20699.379018629465;20700.482487937923;20715.860627665625;6700.93299067003;6989.965050174716;8239.340852731768;8255.545944972964;8287.585620718939;947.1570302430843;1011.9241056920761;1022.3610243597751;1089.7983872983523;1106.9335839849473;28362.574561381596;28576;28859.85570072136;28882.42235155285;29006.854965725037;785.3167744062702;1076.923076923077;1116.7096554895622;1141.646089712192;1194.6893807610084;13086.869131308566;14812.481563145453;15069;15170.696586068218;15426.537203883901;642945;644571;650527;652051;652072;62201.68899155475;62975.37024629695;62997.370026300065;63067;63126;1347.7439286535528;1508.2157278215486;1516.0145905161733;1516.9089854608758;1574.5197714696899;29486.262843429085;30655.846720766254;30905.536416953313;30940.0717978461;31140.68859311378;53192;53255.46744532505;54043.72978135084;54169;54222;451607;452450;453612;453729;460485;24130.638040429058;44731;45906;46260;46655.60033198874;46370.53629463662;47944.28083578679;47976.28035579539;48982.510174899224;49601.75199123981;32096.197595060312;38380.80809595934;39431.08533719965;39512;39902.40146397865;32425;38689;38845.417318739674;39519.012024699616;39546.60453395544;31664.366712666077;38876.222475550894;38930;40544.594554054085;40690.79654601708;33334.66665333316;34553;35390.29219415546;35460;35550.28899421945;25976.610350844374;26054.34864128412;26221.082262120734;26283.342916427242;26421.60367594449 14:48:27 INFO - PROCESS | 1675 | |2;jquery.html;402.605446662271;406.0802282829392;407.824635406775;412.14274309436377;417.8955261184704;14238.857611423882;16376.263068161932;20062.297819576317;20734.274300399487;20745.896270518646;16123.677526449464;16471.505854824343;16904.577385565368;16944.3222271109;19856.900715496402;5207.531322181003;5572.86067848303;5628.846086552247;5800.651960882339;5801.07182850744;7134.894091415839;7547.698092076338;7549.849003019936;7556.848863022737;7563.924360756405;7207.423406127497;7490.850182996337;7546.169921308651;7595.582242976634;7613.16255211929;7505.061867266591;7545.811354716121;7575.659095340723;7580.393568514505;7592.582407967588;7113.324234197743;7487.401007919353;7489.213632568611;7500.662470188855;7504.474686771928;21280.680789788166;27412.862935685393;28130.310482016906;28968;29101.854490727623;2333.988330058356;2370.727366352857;2397.7961873240797;2427.6965379327585;2476.5170791695627;14147.221902795342;14942.850571494255;15232.923835380863;15954.521364359089;16456.6708665826;1782.2603619497893;1849.92600295988;1856.4337876947525;1897.5256185953513;1941.9223231070741;130500.69499305075;130876;134050;136118;136990.31504842412;18025.828321159082;19658.41024769259;20214.292499762436;21238.78761212399;21487.78512214858;27964.440711185664;34858.47712284318;34881.825590871886;35585.82207089;35902;7118;7827.139014708491;8171.182881711781;8359.456635318686;8825.779355516035;8378.03652579961;8560.871586926201;8588.570571471402;8686.913130868737;8696.478211307342;3805.37211360128;4622.14490319292;4655.255159174583;4656.580907718321;4691.483936767021;1840.9907950460163;1890.7163925411023;1979.2182088075417;1979.7723261824785;1983.2166294313954;802.8314054048599;961.5288508630721;974.546835721393;984.1978787288338;1003.9849402258972;18898.244070237037;19873.30443434473;19881.30415435453;19911.800881990996;19975;984.5815528400401;992.2161492421051;1003.6988903328972;1011.367895065584;1100.867895852503;8334.291585215324;9012.549372531346;9783.461909594898;10305.227107966932;10604;603857;610940;611564.2483238576;629987;631726;74205.62897185479;75774;105804.41293380606;130515;130527;1000.1998401278947;1017.4149863828328;1019.97450063749;1036.1192985962023;1037.569408695383;17376.652466950844;17468.47594572165;17709.38017169393;17987.820121798613;17988;51229.7438512805;51278.74360628173;51626;53430;53656.73171634117;73982.19115757459;75302;78265.69538675428;79599;80025.19974800177;7955.721549745731;8097.2307630774985;8226.218509241613;8226.794330141794;8353.707620233263;11140.610078647209;12366.258024518558;12525.963564258273;12625.179363341353;12675.746485070062;10219.182465402899;10738.302010369302;10879.673609791718;11075.224734268524;11139.33164010162;10263.486825658836;10263.794724105626;10846.023857852831;10995;11086.778264434504;10769.192310576582;10815.621453249098;11147.275427097213;11298.548058077586;11531.250468719507;8015.959920200362;8102.959485202537;8151.83696326082;8313.6674533018;8421.115782842713;9864.309498335335;10692.73268168271;10782.73043173927;11236.494357753758;11262.943685281522 14:48:27 INFO - PROCESS | 1675 | |3;mootools.html;1354.3769865861702;1566.1073188282676;1595.4575444348923;1848.5563464768459;1975.8814471131734;7887.763367098989;8550.315974722023;8758.255548278388;8772.03507614162;8777.473351598908;8520.488770673748;8736.344774141933;8744.256738177262;8761.386702930797;8763.649454021848;1845.308009496439;2196.923107691231;2209.745879223886;2400.471896182837;2431.4407686232175;2417.867017314047;2464.494778570389;2470.29596564979;2471.925842224727;2477.3682710908693;2405.3767244428745;2424.333308340203;2443.2181701855325;2456.5578195924727;2466.8643224622638;2134.4770531219797;2193.5064610462678;2200.9889950550146;2458.2625212436287;2464.7165575958725;2121.8302535797097;2172.315720548038;2377.2986968844234;2445.902163913441;2449.889754961031;8546.31629469641;8633.395662303641;8649.610767515476;8651.394402391836;8660.610272537751;5197.272381866542;5371.462853714636;5373.785048598052;5412.431694672082;5419.187121931703;3500.649935006479;3542.769719968194;3620.4026335654585;3634.4184930411006;3683.723720720957;1031.339942436841;1049.7690508088208;1051.6582110814015;1075.1076606416655;1083.8374243863404;11922;11934.92585667294;11935.462904169246;11943.820842687366;11968.401579921056;4776.21192503233;4790.712557246577;4820.842348261824;4870.366852309177;4897.240927656219;10131.936146704636;10138.391696498235;10141.138003269805;10158.593656253815;10181.389116653025;5433.918491222635;5468.644538105011;5475.397706252309;5479.698616576047;5487.478689524489;4337.956620433755;4546.454425468899;4716.151092803259;4919.803207871717;4960.305557221992;3992.4210989406374;3996.140829721624;4078.8164532596106;4102.917941641151;4114.6091121343425;1333.6199183232927;1343.2410687961271;1347.1782212850153;1356.4302992743071;1359.5581436033328;815.6859609050558;849.4308813095241;886.5478605911031;912.1334732004622;937.9249660027182;5993.910091348634;6031.276246850406;6101.237345331833;6163.722632481549;6220.62676239418;961.7307153996892;981.9410835349902;991.7421470417744;1001.0840081325506;1013.229945241607;3908.179282350681;3964.960350396459;4006.8196931137586;4063.5733248009124;4064.146529228895;10591.993760592732;10886;10966.780664386828;11031.22781405294;11036.28264162843;10853.782924341469;10894.346339219672;10900.34597924127;10905.890941090645;10906.890931090746;8264.669413223524;8305.335573154132;8467.576621168917;8566.700165494178;8567.314614830799;5060.2156665716875;5805.158252053344;5836.766529338949;5925.555583331181;6008.759649613967;9134;9458.621655133718;9660.468674222986;9665.855012174683;9897.703068907944;9284.35009549352;9599.376040557341;9814.9018509814;9918.603255869684;9939.204863610748;6751.527393082437;6982.162140542964;7270.345668889825;7535.133459651991;7690.115636701851;8227.300679442315;8298.668053277801;8344.290735287568;8362.749117526484;8369.455985360932;7214.386777123793;7337.339639432476;7457.925420745723;7551.320381165721;7611.847763044819;7065.2934706531;7099.396551293198;7258.0927384076995;7269.600171990592;7379.262073792578;6859.971004349388;6911.619860907497;6933.445324373937;6936.375726184668;6937.236903940663;4746.050789841976;4773.66584339093;4795.256735206049;4915.311856340044;4987.700737955734;4697.624190064855;4769.66612337133;4804.06320767447;4847.3698419205275;5053.772580233825 14:48:27 INFO - PROCESS | 1675 | |4;prototype.html;307.15072823642635;307.307022663893;313.98739066510507;331.04988682481275;341.13011819859355;6510.316416776235;6538.869222615551;6567.7044532996015;6585.868282634357;6617.536772425932;6481;6492.188476440445;6496.12302339184;6505.837354066139;6540.378664026922;4592.655550833692;4637.118947399983;4686.367340409035;4723.952760472385;4769.260764581495;4711.740854253015;4771.997880445093;4808.495108013661;4834.268638219395;4857.927131093019;3516.859325626972;4733.455652599961;4776.259679749644;4776.331313616097;4782.97608511957;4566.041131362401;4608.28571571407;4693.647976401783;4735.502772208903;4786.545278198583;4049.392591111357;4075.3683179107284;4082.101937573729;4084.8570300039355;4091.2635725569385;6185.474234690057;6213.192285002966;6262.342454042306;6265.248170219557;6268.968655156741;5648.4633959773755;5649.971750141264;5680.630759000693;5692.544596432275;5700.2589663343915;6928.515003949726;6929.133858267716;6931.757388491378;6942.826429339257;6957.373836354753;4471.463424389094;4672.859814205579;4681.344611754357;4701.271302948048;4716.457607375161;7312.159101703266;7349.383135710135;7356.742514011984;7358.77923662291;7403.592802395866;4765.69023013503;4769.403824521934;4773.546513081252;4780.426348838162;4780.4741478437345;4135.441715368447;4396.1867054595;4492.685512014193;4824.252240902666;4852.078105160008;4958.281049247839;5459.481349271813;5717.571182161355;5722.799702010409;5728.942710572925;6748.561343512656;7023.438124949992;7029.5430796998035;7044.049053377728;7062.293770623;1656.2960741684976;1665.2506372132589;1681.3022595622679;1684.115839184438;1688.248729315443;840.970566030186;1058.1534772182224;1071.287593750163;1085.9837102443469;1092.4592326798286;6270.561060725706;6328.303886572473;6561.212654481493;6561.967190164019;6568.737250510022;5745.339285982082;6902.275261097613;7348;7364;7379.409647228209;5827.24245848037;6505.772297969643;6521.08704781331;6550.803475895731;6566.967165164144;3526.3828829954655;3876.5154355705536;3884.4173373994126;3890.5720370759195;3899.2201559688174;7339.229380915034;7641.930129781835;7670.4247181461615;7677.078750549858;7684.308412242814;7533.171351151369;7537.773866784005;7551.433642476836;7569.167391586921;7584.317411432998;7633.732819351296;7640.885386719315;7642.04474440695;7651.311381975537;7651.961740191264;7031.578105313698;7242.927570724225;7294.817629559304;7317.158526769277;7351.375133113531;7213.639318034182;7226.277372262732;7237.312455316736;7254.823552933993;7279.090113735783;6477.8704425912165;7367.152777430664;7673.769786906401;7674.961625191839;7690.884636730341;3937.3503371944453;4786.497417771084;4792.496787837227;4792.640551958687;4801.5438533339275;7016.22821489646;7290.198078211286;7303.707851686086;7313.963430182815;7323.890141647773;7016.052832867497;7038.824029399306;7044.401225895856;7050.294970502909;7060.8940865886025;6953.895691564529;7016.508844381048;7020.438364930896;7029.789106326818;7066.752663656747;5549.805756798492;6828.760993365208;6851.2806155354965;6856.9314306856295;6860.622665753432;6778.8983165251575;7046.859062818818;7049;7066.081409416743;7081.327273908971;5499.587530935276;6548.377904099103;6719.294474080151;6903.689333980084;6917 14:48:27 INFO - PROCESS | 1675 | |5;yui.html;604.5224272824469;662.562708612316;738.2396131984058;763.5151678684034;767.9078510578731;15097.471588494405;15152.84847151528;15343.846561534381;15370.231488425561;15390.461333853318;14150.716985660281;14206.36071376788;14243.430262789501;14246.643833904158;14250.572482825532;742.82172278653;750.8235564642305;751.857147142044;752.0899711349274;895.0512456795785;719.2843121094518;912.9497877616728;930.5161316115616;932.6922115701836;937.4937533731777;950.235060776075;982.1406269514175;983.4590974963777;986.013986013986;987.151050096916;763.9121501027397;942.7360339105062;975.6780262513354;979.407458487619;979.7648564344541;713.2974020589714;713.3437237741291;715.7673756029311;723.5188599581272;729.7445893937132;15100.244987750568;15149.091054536762;15166.848331516654;15180.01329913552;15195;12027.699307517294;12296.692582685415;12590.874091259153;12912.870871291261;13103.86896131036;9859.704208873674;9904.851427228597;9954.104130628279;10157.695269141937;10640.787184256349;3463.5670541182353;3480.9477857832153;3516.472529120627;3525.189206482498;3594.281143771256;75642.62178689126;75703.48593028108;75745;76190.61904690496;76427;9275.350725449154;9320.533973301308;9409.717708468757;9506.619735210652;9638.193810855591;20998.790012099988;21059.894700526707;21063.8946805265;21097.9451027448;21107.894460527597;7338.156112047183;7368.306270941145;7476.401887848959;7522.699092036367;7790.415718820997;3926.7447615904875;4057.006033521806;4086.162336720965;4111.239420707119;4132.524759652619;3479.530263414407;3519.1729943463265;3525.5945566259697;3531.346700860349;3544.8404821782565;639.8336432527483;653.0661154548945;653.5947712418331;654.4339146638124;665.4942243894675;549.181719238332;594.9524038076944;597.6503745308956;616.0758861707438;626.0952923026274;7691.807704807425;7784.182660820532;8052.516848989079;8309.916900831035;8457;585.8330375842864;586.5630105571377;608.4371955940737;614.830921496592;618.7246675229479;3656.2321912398693;3867.5165604299464;3894.123822139996;3907.238088572758;4060.2285565743005;85979.57010214908;87138;88524.11475885287;88837.55581222182;90926;58275.70862145747;74537.62731186418;76790.61604691941;79917.60041199757;84697.15302847016;72075.63962180156;72715;73784.15539611445;74607.62696186484;77598.6120069396;6307.842303942438;7303.598302093435;7544.622768861425;7561.697532098655;8004.159563245776;48343.791405215154;48404;48552.75723621359;49729.50270497249;51378.74310628572;65715;69303;71127;71479;71552;11289;11325.830112548483;11422;11493.712657183638;12311.815322769986;11728.179027467995;11843.940780296389;12012.939935300268;12082.395880205779;12462.189957652723;9339;11345.376004319842;11448.084153267551;11735.295882247092;11791;11016.394098324668;11095.500702468247;11297.830532542182;11450.656480305604;11707.2975621463;10984.340939543654;11127.610533631285;11273.379964102052;11557.537698491966;11827.586034488751;8240.258376746122;9412.247020238228;9630.807383852423;9676.85484717744;9699.951500242454;9598.760030999281;9622.615095396106;9692.321537492307;9695;9723.173530250007 14:48:27 INFO - PROCESS | 1675 | __end_tp_report 14:48:27 INFO - PROCESS | 1675 | __start_cc_report 14:48:27 INFO - PROCESS | 1675 | _x_x_mozilla_cycle_collect,1088 14:48:27 INFO - PROCESS | 1675 | __end_cc_report 14:48:27 INFO - PROCESS | 1675 | __startTimestamp1463435307199__endTimestamp 14:48:27 INFO - PROCESS | 1675 | 14:48:27 INFO - PROCESS | 1675 | ------- Summary: start ------- 14:48:27 INFO - PROCESS | 1675 | Number of tests: 6 14:48:27 INFO - PROCESS | 1675 | 14:48:27 INFO - PROCESS | 1675 | [#0] dojo.html Cycles:165 Average:5659.40 Median:5819.30 stddev:637.50 (11.0%) stddev-sans-first:612.70 14:48:27 INFO - PROCESS | 1675 | Values: 3329.6 3389.1 3419.6 3428.4 3489.3 5657.7 5668.9 5676.4 5680.4 5726.4 5616.9 5656.9 5665.9 5667.1 5668.8 5024.0 5110.4 5154.3 5197.0 5215.7 5164.4 5185.6 5199.7 5206.0 5213.9 5140.2 5180.8 5185.1 5214.7 5236.4 5211.2 5224.7 5225.3 5239.0 5275.0 4927.2 4990.5 5075.9 5147.7 5165.0 5474.7 5479.5 5529.4 5539.9 5581.1 6104.9 6169.8 6184.4 6185.3 6193.3 5127.3 6052.1 6149.1 6211.8 6273.7 5756.3 5773.8 5779.9 5802.3 5810.8 6272.8 6274.8 6278.0 6280.5 6292.1 5102.2 5985.3 6092.1 6108.2 6121.2 6251.7 6274.3 6279.4 6279.4 6281.0 6156.5 6179.5 6191.9 6196.8 6203.8 6038.6 6134.0 6144.1 6145.0 6233.8 4796.0 4834.2 4840.2 4863.4 4903.5 4375.8 4387.1 4393.9 4400.1 4426.2 4881.8 5540.6 5569.3 5630.5 5633.4 6089.8 6129.5 6134.5 6140.0 6163.4 5576.4 5582.7 5602.1 5606.0 5612.7 5246.4 5304.7 5306.8 5309.5 5311.9 6201.2 6212.9 6263.8 6286.4 6286.9 5713.7 6096.1 6148.7 6251.7 6260.8 6254.9 6275.6 6275.9 6285.2 6288.1 5956.3 6125.6 6138.2 6163.5 6173.9 6112.6 6148.4 6208.6 6236.2 6243.8 5972.0 6039.4 6054.4 6092.1 6106.2 4337.4 5823.0 5951.2 5960.1 5977.8 5718.1 5833.1 5853.6 5914.9 5921.1 6004.0 6010.3 6019.6 6061.4 6063.6 5723.7 5806.1 5819.3 5839.6 5869.8 14:48:27 INFO - PROCESS | 1675 | 14:48:27 INFO - PROCESS | 1675 | [#1] ext.html Cycles:180 Average:65971.62 Median:196783.64 stddev:137897.42 (70.1%) stddev-sans-first:138196.76 14:48:27 INFO - PROCESS | 1675 | Values: 544.3 548.6 548.9 591.3 634.9 31739.7 32319.8 32489.9 39649.6 40440.2 42682.0 43342.0 43358.3 43359.0 43444.6 9406.5 9589.7 9589.8 9599.6 9615.3 8552.8 8745.7 9345.6 11456.9 11663.4 11380.9 11481.9 11484.3 11496.1 11541.8 8552.3 11305.7 11414.6 11417.3 11424.7 11376.6 11587.7 11593.5 11598.5 11611.0 38288.4 39760.2 40158.6 40244.6 40283.6 3183.6 3236.3 3337.5 3423.2 3508.8 28256.4 29189.0 29519.6 29562.4 29702.9 2751.1 2760.6 2761.8 2785.1 2815.6 358017.0 359674.0 360385.0 493189.0 495190.5 63838.0 64179.4 64266.0 64348.0 64663.7 57885.4 72308.6 72483.9 72614.6 72643.6 15096.7 17463.8 17709.6 17714.4 17758.6 12548.7 12764.7 13381.5 13459.5 13483.5 20079.1 20606.3 20699.4 20700.5 20715.9 6700.9 6990.0 8239.3 8255.5 8287.6 947.2 1011.9 1022.4 1089.8 1106.9 28362.6 28576.0 28859.9 28882.4 29006.9 785.3 1076.9 1116.7 1141.6 1194.7 13086.9 14812.5 15069.0 15170.7 15426.5 642945.0 644571.0 650527.0 652051.0 652072.0 62201.7 62975.4 62997.4 63067.0 63126.0 1347.7 1508.2 1516.0 1516.9 1574.5 29486.3 30655.8 30905.5 30940.1 31140.7 53192.0 53255.5 54043.7 54169.0 54222.0 451607.0 452450.0 453612.0 453729.0 460485.0 24130.6 44731.0 45906.0 46260.0 46655.6 46370.5 47944.3 47976.3 48982.5 49601.8 32096.2 38380.8 39431.1 39512.0 39902.4 32425.0 38689.0 38845.4 39519.0 39546.6 31664.4 38876.2 38930.0 40544.6 40690.8 33334.7 34553.0 35390.3 35460.0 35550.3 25976.6 26054.3 26221.1 26283.3 26421.6 14:48:27 INFO - PROCESS | 1675 | 14:48:27 INFO - PROCESS | 1675 | [#2] jquery.html Cycles:180 Average:36157.18 Median:28047.38 stddev:102699.56 (366.2%) stddev-sans-first:102952.56 14:48:27 INFO - PROCESS | 1675 | Values: 402.6 406.1 407.8 412.1 417.9 14238.9 16376.3 20062.3 20734.3 20745.9 16123.7 16471.5 16904.6 16944.3 19856.9 5207.5 5572.9 5628.8 5800.7 5801.1 7134.9 7547.7 7549.8 7556.8 7563.9 7207.4 7490.9 7546.2 7595.6 7613.2 7505.1 7545.8 7575.7 7580.4 7592.6 7113.3 7487.4 7489.2 7500.7 7504.5 21280.7 27412.9 28130.3 28968.0 29101.9 2334.0 2370.7 2397.8 2427.7 2476.5 14147.2 14942.9 15232.9 15954.5 16456.7 1782.3 1849.9 1856.4 1897.5 1941.9 130500.7 130876.0 134050.0 136118.0 136990.3 18025.8 19658.4 20214.3 21238.8 21487.8 27964.4 34858.5 34881.8 35585.8 35902.0 7118.0 7827.1 8171.2 8359.5 8825.8 8378.0 8560.9 8588.6 8686.9 8696.5 3805.4 4622.1 4655.3 4656.6 4691.5 1841.0 1890.7 1979.2 1979.8 1983.2 802.8 961.5 974.5 984.2 1004.0 18898.2 19873.3 19881.3 19911.8 19975.0 984.6 992.2 1003.7 1011.4 1100.9 8334.3 9012.5 9783.5 10305.2 10604.0 603857.0 610940.0 611564.2 629987.0 631726.0 74205.6 75774.0 105804.4 130515.0 130527.0 1000.2 1017.4 1020.0 1036.1 1037.6 17376.7 17468.5 17709.4 17987.8 17988.0 51229.7 51278.7 51626.0 53430.0 53656.7 73982.2 75302.0 78265.7 79599.0 80025.2 7955.7 8097.2 8226.2 8226.8 8353.7 11140.6 12366.3 12526.0 12625.2 12675.7 10219.2 10738.3 10879.7 11075.2 11139.3 10263.5 10263.8 10846.0 10995.0 11086.8 10769.2 10815.6 11147.3 11298.5 11531.3 8016.0 8103.0 8151.8 8313.7 8421.1 9864.3 10692.7 10782.7 11236.5 11262.9 14:48:27 INFO - PROCESS | 1675 | 14:48:27 INFO - PROCESS | 1675 | [#3] mootools.html Cycles:180 Average:5716.31 Median:4883.80 stddev:3197.11 (65.5%) stddev-sans-first:3189.27 14:48:27 INFO - PROCESS | 1675 | Values: 1354.4 1566.1 1595.5 1848.6 1975.9 7887.8 8550.3 8758.3 8772.0 8777.5 8520.5 8736.3 8744.3 8761.4 8763.6 1845.3 2196.9 2209.7 2400.5 2431.4 2417.9 2464.5 2470.3 2471.9 2477.4 2405.4 2424.3 2443.2 2456.6 2466.9 2134.5 2193.5 2201.0 2458.3 2464.7 2121.8 2172.3 2377.3 2445.9 2449.9 8546.3 8633.4 8649.6 8651.4 8660.6 5197.3 5371.5 5373.8 5412.4 5419.2 3500.6 3542.8 3620.4 3634.4 3683.7 1031.3 1049.8 1051.7 1075.1 1083.8 11922.0 11934.9 11935.5 11943.8 11968.4 4776.2 4790.7 4820.8 4870.4 4897.2 10131.9 10138.4 10141.1 10158.6 10181.4 5433.9 5468.6 5475.4 5479.7 5487.5 4338.0 4546.5 4716.2 4919.8 4960.3 3992.4 3996.1 4078.8 4102.9 4114.6 1333.6 1343.2 1347.2 1356.4 1359.6 815.7 849.4 886.5 912.1 937.9 5993.9 6031.3 6101.2 6163.7 6220.6 961.7 981.9 991.7 1001.1 1013.2 3908.2 3965.0 4006.8 4063.6 4064.1 10592.0 10886.0 10966.8 11031.2 11036.3 10853.8 10894.3 10900.3 10905.9 10906.9 8264.7 8305.3 8467.6 8566.7 8567.3 5060.2 5805.2 5836.8 5925.6 6008.8 9134.0 9458.6 9660.5 9665.9 9897.7 9284.4 9599.4 9814.9 9918.6 9939.2 6751.5 6982.2 7270.3 7535.1 7690.1 8227.3 8298.7 8344.3 8362.7 8369.5 7214.4 7337.3 7457.9 7551.3 7611.8 7065.3 7099.4 7258.1 7269.6 7379.3 6860.0 6911.6 6933.4 6936.4 6937.2 4746.1 4773.7 4795.3 4915.3 4987.7 4697.6 4769.7 4804.1 4847.4 5053.8 14:48:27 INFO - PROCESS | 1675 | 14:48:27 INFO - PROCESS | 1675 | [#4] prototype.html Cycles:180 Average:5717.75 Median:6508.08 stddev:1842.88 (28.3%) stddev-sans-first:1802.75 14:48:27 INFO - PROCESS | 1675 | Values: 307.2 307.3 314.0 331.0 341.1 6510.3 6538.9 6567.7 6585.9 6617.5 6481.0 6492.2 6496.1 6505.8 6540.4 4592.7 4637.1 4686.4 4724.0 4769.3 4711.7 4772.0 4808.5 4834.3 4857.9 3516.9 4733.5 4776.3 4776.3 4783.0 4566.0 4608.3 4693.6 4735.5 4786.5 4049.4 4075.4 4082.1 4084.9 4091.3 6185.5 6213.2 6262.3 6265.2 6269.0 5648.5 5650.0 5680.6 5692.5 5700.3 6928.5 6929.1 6931.8 6942.8 6957.4 4471.5 4672.9 4681.3 4701.3 4716.5 7312.2 7349.4 7356.7 7358.8 7403.6 4765.7 4769.4 4773.5 4780.4 4780.5 4135.4 4396.2 4492.7 4824.3 4852.1 4958.3 5459.5 5717.6 5722.8 5728.9 6748.6 7023.4 7029.5 7044.0 7062.3 1656.3 1665.3 1681.3 1684.1 1688.2 841.0 1058.2 1071.3 1086.0 1092.5 6270.6 6328.3 6561.2 6562.0 6568.7 5745.3 6902.3 7348.0 7364.0 7379.4 5827.2 6505.8 6521.1 6550.8 6567.0 3526.4 3876.5 3884.4 3890.6 3899.2 7339.2 7641.9 7670.4 7677.1 7684.3 7533.2 7537.8 7551.4 7569.2 7584.3 7633.7 7640.9 7642.0 7651.3 7652.0 7031.6 7242.9 7294.8 7317.2 7351.4 7213.6 7226.3 7237.3 7254.8 7279.1 6477.9 7367.2 7673.8 7675.0 7690.9 3937.4 4786.5 4792.5 4792.6 4801.5 7016.2 7290.2 7303.7 7314.0 7323.9 7016.1 7038.8 7044.4 7050.3 7060.9 6953.9 7016.5 7020.4 7029.8 7066.8 5549.8 6828.8 6851.3 6856.9 6860.6 6778.9 7046.9 7049.0 7066.1 7081.3 5499.6 6548.4 6719.3 6903.7 6917.0 14:48:27 INFO - PROCESS | 1675 | 14:48:27 INFO - PROCESS | 1675 | [#5] yui.html Cycles:180 Average:18091.54 Median:653.33 stddev:25244.31 (3863.9%) stddev-sans-first:25280.98 14:48:27 INFO - PROCESS | 1675 | Values: 604.5 662.6 738.2 763.5 767.9 15097.5 15152.8 15343.8 15370.2 15390.5 14150.7 14206.4 14243.4 14246.6 14250.6 742.8 750.8 751.9 752.1 895.1 719.3 912.9 930.5 932.7 937.5 950.2 982.1 983.5 986.0 987.2 763.9 942.7 975.7 979.4 979.8 713.3 713.3 715.8 723.5 729.7 15100.2 15149.1 15166.8 15180.0 15195.0 12027.7 12296.7 12590.9 12912.9 13103.9 9859.7 9904.9 9954.1 10157.7 10640.8 3463.6 3480.9 3516.5 3525.2 3594.3 75642.6 75703.5 75745.0 76190.6 76427.0 9275.4 9320.5 9409.7 9506.6 9638.2 20998.8 21059.9 21063.9 21097.9 21107.9 7338.2 7368.3 7476.4 7522.7 7790.4 3926.7 4057.0 4086.2 4111.2 4132.5 3479.5 3519.2 3525.6 3531.3 3544.8 639.8 653.1 653.6 654.4 665.5 549.2 595.0 597.7 616.1 626.1 7691.8 7784.2 8052.5 8309.9 8457.0 585.8 586.6 608.4 614.8 618.7 3656.2 3867.5 3894.1 3907.2 4060.2 85979.6 87138.0 88524.1 88837.6 90926.0 58275.7 74537.6 76790.6 79917.6 84697.2 72075.6 72715.0 73784.2 74607.6 77598.6 6307.8 7303.6 7544.6 7561.7 8004.2 48343.8 48404.0 48552.8 49729.5 51378.7 65715.0 69303.0 71127.0 71479.0 71552.0 11289.0 11325.8 11422.0 11493.7 12311.8 11728.2 11843.9 12012.9 12082.4 12462.2 9339.0 11345.4 11448.1 11735.3 11791.0 11016.4 11095.5 11297.8 11450.7 11707.3 10984.3 11127.6 11273.4 11557.5 11827.6 8240.3 9412.2 9630.8 9676.9 9700.0 9598.8 9622.6 9692.3 9695.0 9723.2 14:48:27 INFO - PROCESS | 1675 | -------- Summary: end -------- 14:48:27 INFO - PROCESS | 1675 | 14:48:27 INFO - TEST-INFO | 1675: exit 0 14:48:27 INFO - TEST-OK | dromaeo_css | took 1115464ms 14:48:27 INFO - TEST-START | kraken 14:48:27 INFO - operating with platform_type : mac_ 14:48:27 INFO - Initialising browser for kraken test... 14:48:27 INFO - TEST-INFO | started process 1682 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/tmpgn2R2C/profile http://localhost:49280/getInfo.html) 14:48:30 INFO - PROCESS | 1682 | __metrics Screen width/height:1600/1200 14:48:30 INFO - PROCESS | 1682 | colorDepth:24 14:48:30 INFO - PROCESS | 1682 | Browser inner width/height: 1024/689 14:48:30 INFO - PROCESS | 1682 | __metrics 14:48:30 INFO - PROCESS | 1682 | 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" 14:48:30 ERROR - PROCESS | 1682 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 14:48:30 INFO - TEST-INFO | 1682: exit 0 14:48:30 INFO - Browser initialized. 14:48:30 INFO - Running cycle 1/1 for kraken test... 14:48:30 INFO - TEST-INFO | started process 1684 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/tmpgn2R2C/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:48:42 INFO - PROCESS | 1684 | RSS: Main: 211267584 14:48:42 INFO - PROCESS | 1684 | 14:48:45 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection) 14:48:46 INFO - PROCESS | 1684 | RSS: Main: 271421440 14:48:46 INFO - PROCESS | 1684 | 14:48:48 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-dft) 14:48:49 INFO - PROCESS | 1684 | RSS: Main: 285888512 14:48:49 INFO - PROCESS | 1684 | 14:48:52 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-fft) 14:48:53 INFO - PROCESS | 1684 | RSS: Main: 372465664 14:48:53 INFO - PROCESS | 1684 | 14:48:55 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator) 14:48:55 INFO - PROCESS | 1684 | RSS: Main: 278929408 14:48:55 INFO - PROCESS | 1684 | 14:48:58 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:48:58 INFO - PROCESS | 1684 | RSS: Main: 279752704 14:48:58 INFO - PROCESS | 1684 | 14:49:01 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom) 14:49:02 INFO - PROCESS | 1684 | RSS: Main: 318308352 14:49:02 INFO - PROCESS | 1684 | 14:49:04 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate) 14:49:05 INFO - PROCESS | 1684 | RSS: Main: 318189568 14:49:05 INFO - PROCESS | 1684 | 14:49:08 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial) 14:49:08 INFO - PROCESS | 1684 | RSS: Main: 317517824 14:49:08 INFO - PROCESS | 1684 | 14:49:10 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:49:11 INFO - PROCESS | 1684 | RSS: Main: 308969472 14:49:11 INFO - PROCESS | 1684 | 14:49:13 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:49:13 INFO - PROCESS | 1684 | RSS: Main: 315830272 14:49:13 INFO - PROCESS | 1684 | 14:49:15 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:49:16 INFO - PROCESS | 1684 | RSS: Main: 318787584 14:49:16 INFO - PROCESS | 1684 | 14:49:19 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:49:20 INFO - PROCESS | 1684 | RSS: Main: 323751936 14:49:20 INFO - PROCESS | 1684 | 14:49:22 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:49:23 INFO - PROCESS | 1684 | RSS: Main: 321794048 14:49:23 INFO - PROCESS | 1684 | 14:49:25 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49280/tests/kraken/driver.html?testName=ai-astar) 14:49:25 INFO - PROCESS | 1684 | RSS: Main: 386048000 14:49:25 INFO - PROCESS | 1684 | 14:49:25 INFO - PROCESS | 1684 | __start_tp_report 14:49:25 INFO - PROCESS | 1684 | _x_x_mozilla_page_load 14:49:25 INFO - PROCESS | 1684 | _x_x_mozilla_page_load_details 14:49:25 INFO - PROCESS | 1684 | |i|pagename|runs| 14:49:25 INFO - PROCESS | 1684 | |0;ai-astar;114;113;119;112;112;113;113;113;111;134 14:49:25 INFO - PROCESS | 1684 | |1;audio-beat-detection;159;123;143;167;119;140;118;154;157;117 14:49:25 INFO - PROCESS | 1684 | |2;audio-dft;184;185;184;184;183;184;183;185;184;183 14:49:25 INFO - PROCESS | 1684 | |3;audio-fft;70;82;73;154;55;57;57;63;131;57 14:49:25 INFO - PROCESS | 1684 | |4;audio-oscillator;90;88;146;77;82;84;125;79;75;128 14:49:25 INFO - PROCESS | 1684 | |5;imaging-gaussian-blur;87;84;88;84;87;84;86;84;87;84 14:49:25 INFO - PROCESS | 1684 | |6;imaging-darkroom;92;93;92;94;92;97;94;88;130;148 14:49:25 INFO - PROCESS | 1684 | |7;imaging-desaturate;84;84;82;83;83;83;84;85;85;83 14:49:25 INFO - PROCESS | 1684 | |8;json-parse-financial;61;64;64;62;65;63;66;82;60;59 14:49:25 INFO - PROCESS | 1684 | |9;json-stringify-tinderbox;63;65;74;80;102;100;84;68;67;69 14:49:25 INFO - PROCESS | 1684 | |10;stanford-crypto-aes;80;80;81;83;81;82;80;80;83;85 14:49:25 INFO - PROCESS | 1684 | |11;stanford-crypto-ccm;129;125;131;122;125;162;144;131;177;137 14:49:25 INFO - PROCESS | 1684 | |12;stanford-crypto-pbkdf2;160;166;169;172;171;170;166;163;161;166 14:49:25 INFO - PROCESS | 1684 | |13;stanford-crypto-sha256-iterative;68;67;68;69;68;67;66;69;72;88 14:49:25 INFO - PROCESS | 1684 | __end_tp_report 14:49:25 INFO - PROCESS | 1684 | __start_cc_report 14:49:25 INFO - PROCESS | 1684 | _x_x_mozilla_cycle_collect,2018 14:49:25 INFO - PROCESS | 1684 | __end_cc_report 14:49:25 INFO - PROCESS | 1684 | __startTimestamp1463435365435__endTimestamp 14:49:25 INFO - PROCESS | 1684 | 14:49:25 INFO - PROCESS | 1684 | ------- Summary: start ------- 14:49:25 INFO - PROCESS | 1684 | Number of tests: 14 14:49:25 INFO - PROCESS | 1684 | 14:49:25 INFO - PROCESS | 1684 | [#0] ai-astar Cycles:10 Average:115.40 Median:113.00 stddev:6.88 (6.1%) stddev-sans-first:7.28 14:49:25 INFO - PROCESS | 1684 | Values: 114.0 113.0 119.0 112.0 112.0 113.0 113.0 113.0 111.0 134.0 14:49:25 INFO - PROCESS | 1684 | 14:49:25 INFO - PROCESS | 1684 | [#1] audio-beat-detection Cycles:10 Average:139.70 Median:148.50 stddev:19.22 (12.9%) stddev-sans-first:19.08 14:49:25 INFO - PROCESS | 1684 | Values: 159.0 123.0 143.0 167.0 119.0 140.0 118.0 154.0 157.0 117.0 14:49:25 INFO - PROCESS | 1684 | 14:49:25 INFO - PROCESS | 1684 | [#2] audio-dft Cycles:10 Average:183.90 Median:184.00 stddev:0.74 (0.4%) stddev-sans-first:0.78 14:49:25 INFO - PROCESS | 1684 | Values: 184.0 185.0 184.0 184.0 183.0 184.0 183.0 185.0 184.0 183.0 14:49:25 INFO - PROCESS | 1684 | 14:49:25 INFO - PROCESS | 1684 | [#3] audio-fft Cycles:10 Average:79.90 Median:60.00 stddev:34.53 (57.6%) stddev-sans-first:36.44 14:49:25 INFO - PROCESS | 1684 | Values: 70.0 82.0 73.0 154.0 55.0 57.0 57.0 63.0 131.0 57.0 14:49:25 INFO - PROCESS | 1684 | 14:49:25 INFO - PROCESS | 1684 | [#4] audio-oscillator Cycles:10 Average:97.40 Median:80.50 stddev:25.55 (31.7%) stddev-sans-first:26.96 14:49:25 INFO - PROCESS | 1684 | Values: 90.0 88.0 146.0 77.0 82.0 84.0 125.0 79.0 75.0 128.0 14:49:25 INFO - PROCESS | 1684 | 14:49:25 INFO - PROCESS | 1684 | [#5] imaging-gaussian-blur Cycles:10 Average:85.50 Median:86.50 stddev:1.65 (1.9%) stddev-sans-first:1.66 14:49:25 INFO - PROCESS | 1684 | Values: 87.0 84.0 88.0 84.0 87.0 84.0 86.0 84.0 87.0 84.0 14:49:25 INFO - PROCESS | 1684 | 14:49:25 INFO - PROCESS | 1684 | [#6] imaging-darkroom Cycles:10 Average:102.00 Median:92.50 stddev:20.08 (21.7%) stddev-sans-first:20.97 14:49:25 INFO - PROCESS | 1684 | Values: 92.0 93.0 92.0 94.0 92.0 97.0 94.0 88.0 130.0 148.0 14:49:25 INFO - PROCESS | 1684 | 14:49:25 INFO - PROCESS | 1684 | [#7] imaging-desaturate Cycles:10 Average:83.60 Median:84.00 stddev:0.97 (1.2%) stddev-sans-first:1.01 14:49:25 INFO - PROCESS | 1684 | Values: 84.0 84.0 82.0 83.0 83.0 83.0 84.0 85.0 85.0 83.0 14:49:25 INFO - PROCESS | 1684 | 14:49:25 INFO - PROCESS | 1684 | [#8] json-parse-financial Cycles:10 Average:64.60 Median:64.00 stddev:6.50 (10.2%) stddev-sans-first:6.76 14:49:25 INFO - PROCESS | 1684 | Values: 61.0 64.0 64.0 62.0 65.0 63.0 66.0 82.0 60.0 59.0 14:49:25 INFO - PROCESS | 1684 | 14:49:25 INFO - PROCESS | 1684 | [#9] json-stringify-tinderbox Cycles:10 Average:77.20 Median:68.50 stddev:14.16 (20.7%) stddev-sans-first:14.06 14:49:25 INFO - PROCESS | 1684 | Values: 63.0 65.0 74.0 80.0 102.0 100.0 84.0 68.0 67.0 69.0 14:49:25 INFO - PROCESS | 1684 | 14:49:25 INFO - PROCESS | 1684 | [#10] stanford-crypto-aes Cycles:10 Average:81.50 Median:81.50 stddev:1.72 (2.1%) stddev-sans-first:1.73 14:49:25 INFO - PROCESS | 1684 | Values: 80.0 80.0 81.0 83.0 81.0 82.0 80.0 80.0 83.0 85.0 14:49:25 INFO - PROCESS | 1684 | 14:49:25 INFO - PROCESS | 1684 | [#11] stanford-crypto-ccm Cycles:10 Average:138.30 Median:134.00 stddev:17.97 (13.4%) stddev-sans-first:18.74 14:49:25 INFO - PROCESS | 1684 | Values: 129.0 125.0 131.0 122.0 125.0 162.0 144.0 131.0 177.0 137.0 14:49:25 INFO - PROCESS | 1684 | 14:49:25 INFO - PROCESS | 1684 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.40 Median:167.50 stddev:4.14 (2.5%) stddev-sans-first:3.69 14:49:25 INFO - PROCESS | 1684 | Values: 160.0 166.0 169.0 172.0 171.0 170.0 166.0 163.0 161.0 166.0 14:49:25 INFO - PROCESS | 1684 | 14:49:25 INFO - PROCESS | 1684 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.20 Median:68.50 stddev:6.46 (9.4%) stddev-sans-first:6.80 14:49:25 INFO - PROCESS | 1684 | Values: 68.0 67.0 68.0 69.0 68.0 67.0 66.0 69.0 72.0 88.0 14:49:25 INFO - PROCESS | 1684 | -------- Summary: end -------- 14:49:25 INFO - PROCESS | 1684 | 14:49:26 INFO - TEST-INFO | 1684: exit 0 14:49:26 INFO - TEST-OK | kraken | took 58478ms 14:49:26 INFO - SUITE-END | took 1173s 14:49:26 INFO - Completed test suite (00:19:34) 14:49:26 INFO - javascript benchmark 14:49:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3329.6337402885683, 3389.0510657016043, 3419.555457790487, 3428.40002999475, 3489.3370259650665, 5657.717114144297, 5668.943310566893, 5676.432356764321, 5680.375158732536, 5726.3700992890745, 5616.887662246752, 5656.858578535539, 5665.886682266352, 5667.121596152603, 5668.829935101953, 5023.974880125613, 5110.412302585213, 5154.252633368159, 5196.974015129919, 5215.713135777531, 5164.431912489623, 5185.559227465651, 5199.714015729133, 5206.010857936999, 5213.947860521384, 5140.177571588547, 5180.818671346504, 5185.144451165553, 5214.687118772867, 5236.3978142513715, 5211.1922651989, 5224.712640804754, 5225.320708307933, 5239.0, 5274.973625131888, 4927.1870141426625, 4990.451050384455, 5075.949240507584, 5147.742612869341, 5165.0, 5474.671519708791, 5479.506844383985, 5529.391766905637, 5539.861503462445, 5581.079121944874, 6104.908426373563, 6169.81490555284, 6184.412480814315, 6185.288691800455, 6193.2877719062435, 5127.333446651912, 6052.050818140233, 6149.108379285023, 6211.844703882394, 6273.749050037993, 5756.30924289088, 5773.826785196451, 5779.855503612359, 5802.274715660543, 5810.767569297223, 6272.8118156455375, 6274.843128921813, 6277.968610157011, 6280.497560195174, 6292.119103325538, 5102.2346648003095, 5985.311689155716, 6092.055731361646, 6108.205933228652, 6121.204243448323, 6251.687415629201, 6274.2784579773, 6279.3720627937755, 6279.403456671609, 6280.9685951569945, 6156.476699480594, 6179.536534759911, 6191.938080619227, 6196.845078872974, 6203.751849925952, 6038.63768173911, 6133.977404287646, 6144.109104179869, 6144.986077297196, 6233.812985610439, 4796.0, 4834.226523756252, 4840.2255639097575, 4863.367762190893, 4903.485134060944, 4375.759333236639, 4387.144506821203, 4393.912121757547, 4400.141972315432, 4426.247537918497, 4881.755912204447, 5540.63985840919, 5569.275994120739, 5630.493255607015, 5633.408492108351, 6089.756409743649, 6129.450483694044, 6134.5092392608485, 6139.9828168395225, 6163.3836616338, 5576.442355764472, 5582.720863956786, 5602.075657516546, 5605.971970140205, 5612.719364031782, 5246.449122842047, 5304.681719096867, 5306.814261500829, 5309.522143007148, 5311.93761247752, 6201.162843016135, 6212.875742485216, 6263.843403914848, 6286.371362863677, 6286.937130628727, 5713.742976545153, 6096.116063170904, 6148.723307451265, 6251.6874156292915, 6260.843478913063, 6254.937450625435, 6275.571630539642, 6275.874482510416, 6285.151504546828, 6288.056791481132, 5956.315023772322, 6125.571210015256, 6138.232720909887, 6163.537734669828, 6173.938260617337, 6112.5946701277735, 6148.44663980244, 6208.565400422108, 6236.158118654105, 6243.8126856194385, 5972.014617588223, 6039.396060393926, 6054.3642917493025, 6092.116643086816, 6106.202605869653, 4337.414449049338, 5823.0, 5951.196588460502, 5960.105984102419, 5977.790777322773, 5718.1136923776885, 5833.125031245348, 5853.560982926212, 5914.881702366015, 5921.052631578927, 6004.0, 6010.338862725184, 6019.578629495893, 6061.393860613904, 6063.605865618721, 5723.6851973141875, 5806.1000544915605, 5819.301683797971, 5839.591228613956, 5869.8239052828485], "value": 5620.507622309592, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [544.3060098374574, 548.5967813656963, 548.9121740521515, 591.2874985642302, 634.8825467288552, 31739.68260317399, 32319.838400807992, 32489.862854800085, 39649.60350396495, 40440.191196176056, 42682.0, 43342.0, 43358.349624755654, 43359.0, 43444.565554344445, 9406.529673516314, 9589.66436174734, 9589.808203835919, 9599.568019439143, 9615.326927115104, 8552.828943421127, 8745.650173993032, 9345.579448924815, 11456.942715286412, 11663.41682915855, 11380.943095284512, 11481.942590287037, 11484.310941343505, 11496.137789665767, 11541.826872596876, 8552.27305679018, 11305.717357066098, 11414.600488982887, 11417.31496110236, 11424.657260282205, 11376.601818936297, 11587.710307242301, 11593.478293476814, 11598.478068486855, 11611.0, 38288.42567361492, 39760.20479590392, 40158.59841401578, 40244.597554024374, 40283.57589604858, 3183.570218020561, 3236.2718388362664, 3337.4826901830256, 3423.1613254752747, 3508.8070156141657, 28256.43487130246, 29189.0, 29519.55720664192, 29562.40875182484, 29702.85148574265, 2751.0508874438287, 2760.647282831418, 2761.8066735328534, 2785.1366076516347, 2815.6480439945008, 358017.0, 359674.0, 360385.0, 493189.0, 495190.52404738107, 63838.0, 64179.35820641827, 64266.0, 64348.0, 64663.67668161723, 57885.421145788845, 72308.63845680738, 72483.91274130892, 72614.63692681503, 72643.63678181576, 15096.698066038618, 17463.77753557264, 17709.557261068578, 17714.379996700052, 17758.644827103384, 12548.68628284282, 12764.744705106032, 13381.53164639233, 13459.461621535225, 13483.528076517276, 20079.096440660207, 20606.27878024292, 20699.379018629465, 20700.482487937923, 20715.860627665625, 6700.93299067003, 6989.965050174716, 8239.340852731768, 8255.545944972964, 8287.585620718939, 947.1570302430843, 1011.9241056920761, 1022.3610243597751, 1089.7983872983523, 1106.9335839849473, 28362.574561381596, 28576.0, 28859.85570072136, 28882.42235155285, 29006.854965725037, 785.3167744062702, 1076.923076923077, 1116.7096554895622, 1141.646089712192, 1194.6893807610084, 13086.869131308566, 14812.481563145453, 15069.0, 15170.696586068218, 15426.537203883901, 642945.0, 644571.0, 650527.0, 652051.0, 652072.0, 62201.68899155475, 62975.37024629695, 62997.370026300065, 63067.0, 63126.0, 1347.7439286535528, 1508.2157278215486, 1516.0145905161733, 1516.9089854608758, 1574.5197714696899, 29486.262843429085, 30655.846720766254, 30905.536416953313, 30940.0717978461, 31140.68859311378, 53192.0, 53255.46744532505, 54043.72978135084, 54169.0, 54222.0, 451607.0, 452450.0, 453612.0, 453729.0, 460485.0, 24130.638040429058, 44731.0, 45906.0, 46260.0, 46655.60033198874, 46370.53629463662, 47944.28083578679, 47976.28035579539, 48982.510174899224, 49601.75199123981, 32096.197595060312, 38380.80809595934, 39431.08533719965, 39512.0, 39902.40146397865, 32425.0, 38689.0, 38845.417318739674, 39519.012024699616, 39546.60453395544, 31664.366712666077, 38876.222475550894, 38930.0, 40544.594554054085, 40690.79654601708, 33334.66665333316, 34553.0, 35390.29219415546, 35460.0, 35550.28899421945, 25976.610350844374, 26054.34864128412, 26221.082262120734, 26283.342916427242, 26421.60367594449], "value": 20542.44052638345, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [402.605446662271, 406.0802282829392, 407.824635406775, 412.14274309436377, 417.8955261184704, 14238.857611423882, 16376.263068161932, 20062.297819576317, 20734.274300399487, 20745.896270518646, 16123.677526449464, 16471.505854824343, 16904.577385565368, 16944.3222271109, 19856.900715496402, 5207.531322181003, 5572.86067848303, 5628.846086552247, 5800.651960882339, 5801.07182850744, 7134.894091415839, 7547.698092076338, 7549.849003019936, 7556.848863022737, 7563.924360756405, 7207.423406127497, 7490.850182996337, 7546.169921308651, 7595.582242976634, 7613.16255211929, 7505.061867266591, 7545.811354716121, 7575.659095340723, 7580.393568514505, 7592.582407967588, 7113.324234197743, 7487.401007919353, 7489.213632568611, 7500.662470188855, 7504.474686771928, 21280.680789788166, 27412.862935685393, 28130.310482016906, 28968.0, 29101.854490727623, 2333.988330058356, 2370.727366352857, 2397.7961873240797, 2427.6965379327585, 2476.5170791695627, 14147.221902795342, 14942.850571494255, 15232.923835380863, 15954.521364359089, 16456.6708665826, 1782.2603619497893, 1849.92600295988, 1856.4337876947525, 1897.5256185953513, 1941.9223231070741, 130500.69499305075, 130876.0, 134050.0, 136118.0, 136990.31504842412, 18025.828321159082, 19658.41024769259, 20214.292499762436, 21238.78761212399, 21487.78512214858, 27964.440711185664, 34858.47712284318, 34881.825590871886, 35585.82207089, 35902.0, 7118.0, 7827.139014708491, 8171.182881711781, 8359.456635318686, 8825.779355516035, 8378.03652579961, 8560.871586926201, 8588.570571471402, 8686.913130868737, 8696.478211307342, 3805.37211360128, 4622.14490319292, 4655.255159174583, 4656.580907718321, 4691.483936767021, 1840.9907950460163, 1890.7163925411023, 1979.2182088075417, 1979.7723261824785, 1983.2166294313954, 802.8314054048599, 961.5288508630721, 974.546835721393, 984.1978787288338, 1003.9849402258972, 18898.244070237037, 19873.30443434473, 19881.30415435453, 19911.800881990996, 19975.0, 984.5815528400401, 992.2161492421051, 1003.6988903328972, 1011.367895065584, 1100.867895852503, 8334.291585215324, 9012.549372531346, 9783.461909594898, 10305.227107966932, 10604.0, 603857.0, 610940.0, 611564.2483238576, 629987.0, 631726.0, 74205.62897185479, 75774.0, 105804.41293380606, 130515.0, 130527.0, 1000.1998401278947, 1017.4149863828328, 1019.97450063749, 1036.1192985962023, 1037.569408695383, 17376.652466950844, 17468.47594572165, 17709.38017169393, 17987.820121798613, 17988.0, 51229.7438512805, 51278.74360628173, 51626.0, 53430.0, 53656.73171634117, 73982.19115757459, 75302.0, 78265.69538675428, 79599.0, 80025.19974800177, 7955.721549745731, 8097.2307630774985, 8226.218509241613, 8226.794330141794, 8353.707620233263, 11140.610078647209, 12366.258024518558, 12525.963564258273, 12625.179363341353, 12675.746485070062, 10219.182465402899, 10738.302010369302, 10879.673609791718, 11075.224734268524, 11139.33164010162, 10263.486825658836, 10263.794724105626, 10846.023857852831, 10995.0, 11086.778264434504, 10769.192310576582, 10815.621453249098, 11147.275427097213, 11298.548058077586, 11531.250468719507, 8015.959920200362, 8102.959485202537, 8151.83696326082, 8313.6674533018, 8421.115782842713, 9864.309498335335, 10692.73268168271, 10782.73043173927, 11236.494357753758, 11262.943685281522], "value": 10229.467422561145, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1354.3769865861702, 1566.1073188282676, 1595.4575444348923, 1848.5563464768459, 1975.8814471131734, 7887.763367098989, 8550.315974722023, 8758.255548278388, 8772.03507614162, 8777.473351598908, 8520.488770673748, 8736.344774141933, 8744.256738177262, 8761.386702930797, 8763.649454021848, 1845.308009496439, 2196.923107691231, 2209.745879223886, 2400.471896182837, 2431.4407686232175, 2417.867017314047, 2464.494778570389, 2470.29596564979, 2471.925842224727, 2477.3682710908693, 2405.3767244428745, 2424.333308340203, 2443.2181701855325, 2456.5578195924727, 2466.8643224622638, 2134.4770531219797, 2193.5064610462678, 2200.9889950550146, 2458.2625212436287, 2464.7165575958725, 2121.8302535797097, 2172.315720548038, 2377.2986968844234, 2445.902163913441, 2449.889754961031, 8546.31629469641, 8633.395662303641, 8649.610767515476, 8651.394402391836, 8660.610272537751, 5197.272381866542, 5371.462853714636, 5373.785048598052, 5412.431694672082, 5419.187121931703, 3500.649935006479, 3542.769719968194, 3620.4026335654585, 3634.4184930411006, 3683.723720720957, 1031.339942436841, 1049.7690508088208, 1051.6582110814015, 1075.1076606416655, 1083.8374243863404, 11922.0, 11934.92585667294, 11935.462904169246, 11943.820842687366, 11968.401579921056, 4776.21192503233, 4790.712557246577, 4820.842348261824, 4870.366852309177, 4897.240927656219, 10131.936146704636, 10138.391696498235, 10141.138003269805, 10158.593656253815, 10181.389116653025, 5433.918491222635, 5468.644538105011, 5475.397706252309, 5479.698616576047, 5487.478689524489, 4337.956620433755, 4546.454425468899, 4716.151092803259, 4919.803207871717, 4960.305557221992, 3992.4210989406374, 3996.140829721624, 4078.8164532596106, 4102.917941641151, 4114.6091121343425, 1333.6199183232927, 1343.2410687961271, 1347.1782212850153, 1356.4302992743071, 1359.5581436033328, 815.6859609050558, 849.4308813095241, 886.5478605911031, 912.1334732004622, 937.9249660027182, 5993.910091348634, 6031.276246850406, 6101.237345331833, 6163.722632481549, 6220.62676239418, 961.7307153996892, 981.9410835349902, 991.7421470417744, 1001.0840081325506, 1013.229945241607, 3908.179282350681, 3964.960350396459, 4006.8196931137586, 4063.5733248009124, 4064.146529228895, 10591.993760592732, 10886.0, 10966.780664386828, 11031.22781405294, 11036.28264162843, 10853.782924341469, 10894.346339219672, 10900.34597924127, 10905.890941090645, 10906.890931090746, 8264.669413223524, 8305.335573154132, 8467.576621168917, 8566.700165494178, 8567.314614830799, 5060.2156665716875, 5805.158252053344, 5836.766529338949, 5925.555583331181, 6008.759649613967, 9134.0, 9458.621655133718, 9660.468674222986, 9665.855012174683, 9897.703068907944, 9284.35009549352, 9599.376040557341, 9814.9018509814, 9918.603255869684, 9939.204863610748, 6751.527393082437, 6982.162140542964, 7270.345668889825, 7535.133459651991, 7690.115636701851, 8227.300679442315, 8298.668053277801, 8344.290735287568, 8362.749117526484, 8369.455985360932, 7214.386777123793, 7337.339639432476, 7457.925420745723, 7551.320381165721, 7611.847763044819, 7065.2934706531, 7099.396551293198, 7258.0927384076995, 7269.600171990592, 7379.262073792578, 6859.971004349388, 6911.619860907497, 6933.445324373937, 6936.375726184668, 6937.236903940663, 4746.050789841976, 4773.66584339093, 4795.256735206049, 4915.311856340044, 4987.700737955734, 4697.624190064855, 4769.66612337133, 4804.06320767447, 4847.3698419205275, 5053.772580233825], "value": 4605.2452007155225, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [307.15072823642635, 307.307022663893, 313.98739066510507, 331.04988682481275, 341.13011819859355, 6510.316416776235, 6538.869222615551, 6567.7044532996015, 6585.868282634357, 6617.536772425932, 6481.0, 6492.188476440445, 6496.12302339184, 6505.837354066139, 6540.378664026922, 4592.655550833692, 4637.118947399983, 4686.367340409035, 4723.952760472385, 4769.260764581495, 4711.740854253015, 4771.997880445093, 4808.495108013661, 4834.268638219395, 4857.927131093019, 3516.859325626972, 4733.455652599961, 4776.259679749644, 4776.331313616097, 4782.97608511957, 4566.041131362401, 4608.28571571407, 4693.647976401783, 4735.502772208903, 4786.545278198583, 4049.392591111357, 4075.3683179107284, 4082.101937573729, 4084.8570300039355, 4091.2635725569385, 6185.474234690057, 6213.192285002966, 6262.342454042306, 6265.248170219557, 6268.968655156741, 5648.4633959773755, 5649.971750141264, 5680.630759000693, 5692.544596432275, 5700.2589663343915, 6928.515003949726, 6929.133858267716, 6931.757388491378, 6942.826429339257, 6957.373836354753, 4471.463424389094, 4672.859814205579, 4681.344611754357, 4701.271302948048, 4716.457607375161, 7312.159101703266, 7349.383135710135, 7356.742514011984, 7358.77923662291, 7403.592802395866, 4765.69023013503, 4769.403824521934, 4773.546513081252, 4780.426348838162, 4780.4741478437345, 4135.441715368447, 4396.1867054595, 4492.685512014193, 4824.252240902666, 4852.078105160008, 4958.281049247839, 5459.481349271813, 5717.571182161355, 5722.799702010409, 5728.942710572925, 6748.561343512656, 7023.438124949992, 7029.5430796998035, 7044.049053377728, 7062.293770623, 1656.2960741684976, 1665.2506372132589, 1681.3022595622679, 1684.115839184438, 1688.248729315443, 840.970566030186, 1058.1534772182224, 1071.287593750163, 1085.9837102443469, 1092.4592326798286, 6270.561060725706, 6328.303886572473, 6561.212654481493, 6561.967190164019, 6568.737250510022, 5745.339285982082, 6902.275261097613, 7348.0, 7364.0, 7379.409647228209, 5827.24245848037, 6505.772297969643, 6521.08704781331, 6550.803475895731, 6566.967165164144, 3526.3828829954655, 3876.5154355705536, 3884.4173373994126, 3890.5720370759195, 3899.2201559688174, 7339.229380915034, 7641.930129781835, 7670.4247181461615, 7677.078750549858, 7684.308412242814, 7533.171351151369, 7537.773866784005, 7551.433642476836, 7569.167391586921, 7584.317411432998, 7633.732819351296, 7640.885386719315, 7642.04474440695, 7651.311381975537, 7651.961740191264, 7031.578105313698, 7242.927570724225, 7294.817629559304, 7317.158526769277, 7351.375133113531, 7213.639318034182, 7226.277372262732, 7237.312455316736, 7254.823552933993, 7279.090113735783, 6477.8704425912165, 7367.152777430664, 7673.769786906401, 7674.961625191839, 7690.884636730341, 3937.3503371944453, 4786.497417771084, 4792.496787837227, 4792.640551958687, 4801.5438533339275, 7016.22821489646, 7290.198078211286, 7303.707851686086, 7313.963430182815, 7323.890141647773, 7016.052832867497, 7038.824029399306, 7044.401225895856, 7050.294970502909, 7060.8940865886025, 6953.895691564529, 7016.508844381048, 7020.438364930896, 7029.789106326818, 7066.752663656747, 5549.805756798492, 6828.760993365208, 6851.2806155354965, 6856.9314306856295, 6860.622665753432, 6778.8983165251575, 7046.859062818818, 7049.0, 7066.081409416743, 7081.327273908971, 5499.587530935276, 6548.377904099103, 6719.294474080151, 6903.689333980084, 6917.0], "value": 5100.276729828423, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [604.5224272824469, 662.562708612316, 738.2396131984058, 763.5151678684034, 767.9078510578731, 15097.471588494405, 15152.84847151528, 15343.846561534381, 15370.231488425561, 15390.461333853318, 14150.716985660281, 14206.36071376788, 14243.430262789501, 14246.643833904158, 14250.572482825532, 742.82172278653, 750.8235564642305, 751.857147142044, 752.0899711349274, 895.0512456795785, 719.2843121094518, 912.9497877616728, 930.5161316115616, 932.6922115701836, 937.4937533731777, 950.235060776075, 982.1406269514175, 983.4590974963777, 986.013986013986, 987.151050096916, 763.9121501027397, 942.7360339105062, 975.6780262513354, 979.407458487619, 979.7648564344541, 713.2974020589714, 713.3437237741291, 715.7673756029311, 723.5188599581272, 729.7445893937132, 15100.244987750568, 15149.091054536762, 15166.848331516654, 15180.01329913552, 15195.0, 12027.699307517294, 12296.692582685415, 12590.874091259153, 12912.870871291261, 13103.86896131036, 9859.704208873674, 9904.851427228597, 9954.104130628279, 10157.695269141937, 10640.787184256349, 3463.5670541182353, 3480.9477857832153, 3516.472529120627, 3525.189206482498, 3594.281143771256, 75642.62178689126, 75703.48593028108, 75745.0, 76190.61904690496, 76427.0, 9275.350725449154, 9320.533973301308, 9409.717708468757, 9506.619735210652, 9638.193810855591, 20998.790012099988, 21059.894700526707, 21063.8946805265, 21097.9451027448, 21107.894460527597, 7338.156112047183, 7368.306270941145, 7476.401887848959, 7522.699092036367, 7790.415718820997, 3926.7447615904875, 4057.006033521806, 4086.162336720965, 4111.239420707119, 4132.524759652619, 3479.530263414407, 3519.1729943463265, 3525.5945566259697, 3531.346700860349, 3544.8404821782565, 639.8336432527483, 653.0661154548945, 653.5947712418331, 654.4339146638124, 665.4942243894675, 549.181719238332, 594.9524038076944, 597.6503745308956, 616.0758861707438, 626.0952923026274, 7691.807704807425, 7784.182660820532, 8052.516848989079, 8309.916900831035, 8457.0, 585.8330375842864, 586.5630105571377, 608.4371955940737, 614.830921496592, 618.7246675229479, 3656.2321912398693, 3867.5165604299464, 3894.123822139996, 3907.238088572758, 4060.2285565743005, 85979.57010214908, 87138.0, 88524.11475885287, 88837.55581222182, 90926.0, 58275.70862145747, 74537.62731186418, 76790.61604691941, 79917.60041199757, 84697.15302847016, 72075.63962180156, 72715.0, 73784.15539611445, 74607.62696186484, 77598.6120069396, 6307.842303942438, 7303.598302093435, 7544.622768861425, 7561.697532098655, 8004.159563245776, 48343.791405215154, 48404.0, 48552.75723621359, 49729.50270497249, 51378.74310628572, 65715.0, 69303.0, 71127.0, 71479.0, 71552.0, 11289.0, 11325.830112548483, 11422.0, 11493.712657183638, 12311.815322769986, 11728.179027467995, 11843.940780296389, 12012.939935300268, 12082.395880205779, 12462.189957652723, 9339.0, 11345.376004319842, 11448.084153267551, 11735.295882247092, 11791.0, 11016.394098324668, 11095.500702468247, 11297.830532542182, 11450.656480305604, 11707.2975621463, 10984.340939543654, 11127.610533631285, 11273.379964102052, 11557.537698491966, 11827.586034488751, 8240.258376746122, 9412.247020238228, 9630.807383852423, 9676.85484717744, 9699.951500242454, 9598.760030999281, 9622.615095396106, 9692.321537492307, 9695.0, 9723.173530250007], "value": 6855.382265029253, "unit": "score"}], "name": "dromaeo_css", "value": 7583.443780032237, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 119.0, 112.0, 112.0, 113.0, 113.0, 113.0, 111.0, 134.0], "value": 115.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 123.0, 143.0, 167.0, 119.0, 140.0, 118.0, 154.0, 157.0, 117.0], "value": 139.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 185.0, 184.0, 184.0, 183.0, 184.0, 183.0, 185.0, 184.0, 183.0], "value": 183.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 82.0, 73.0, 154.0, 55.0, 57.0, 57.0, 63.0, 131.0, 57.0], "value": 79.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 146.0, 77.0, 82.0, 84.0, 125.0, 79.0, 75.0, 128.0], "value": 97.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 88.0, 84.0, 87.0, 84.0, 86.0, 84.0, 87.0, 84.0], "value": 85.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 92.0, 94.0, 92.0, 97.0, 94.0, 88.0, 130.0, 148.0], "value": 102.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 82.0, 83.0, 83.0, 83.0, 84.0, 85.0, 85.0, 83.0], "value": 83.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 64.0, 64.0, 62.0, 65.0, 63.0, 66.0, 82.0, 60.0, 59.0], "value": 64.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 74.0, 80.0, 102.0, 100.0, 84.0, 68.0, 67.0, 69.0], "value": 77.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 80.0, 81.0, 83.0, 81.0, 82.0, 80.0, 80.0, 83.0, 85.0], "value": 81.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 125.0, 131.0, 122.0, 125.0, 162.0, 144.0, 131.0, 177.0, 137.0], "value": 138.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 166.0, 169.0, 172.0, 171.0, 170.0, 166.0, 163.0, 161.0, 166.0], "value": 166.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 68.0, 69.0, 68.0, 67.0, 66.0, 69.0, 72.0, 88.0], "value": 70.2, "unit": "score"}], "name": "kraken", "value": 1485.6000000000001, "alertThreshold": 2.0}]} 14:49:26 INFO - Return code: 0 14:49:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 14:49:26 ERROR - # TBPL SUCCESS # 14:49:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:49:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:49:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:49:26 INFO - 2016-05-16 14:49:26.689 screenresolution[1688:10652] starting screenresolution argv=screenresolution get 14:49:26 INFO - 2016-05-16 14:49:26.692 screenresolution[1688:10652] Display 0: 1600x1200x32@60 14:49:26 INFO - 2016-05-16 14:49:26.698 screenresolution[1689:10655] starting screenresolution argv=screenresolution list 14:49:26 INFO - Available Modes on Display 0 14:49:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:49:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:49:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:49:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:49:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:49:26 INFO - Intel Iris: 14:49:26 INFO - Chipset Model: Intel Iris 14:49:26 INFO - Type: GPU 14:49:26 INFO - Bus: Built-In 14:49:26 INFO - VRAM (Dynamic, Max): 1536 MB 14:49:26 INFO - Vendor: Intel (0x8086) 14:49:26 INFO - Device ID: 0x0a2e 14:49:26 INFO - Revision ID: 0x0009 14:49:26 INFO - Displays: 14:49:26 INFO - Display: 14:49:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:49:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:49:26 INFO - Display Serial Number: 1600x1200 60 14:49:26 INFO - Main Display: Yes 14:49:26 INFO - Mirror: Off 14:49:26 INFO - Online: Yes 14:49:26 INFO - Rotation: Supported 14:49:26 INFO - Return code: 0 14:49:26 INFO - Running post-action listener: _resource_record_post_action 14:49:26 INFO - Running post-run listener: _resource_record_post_run 14:49:27 INFO - Total resource usage - Wall time: 1207s; CPU: 53.0%; Read bytes: 31448576; Write bytes: 652803584; Read time: 197; Write time: 1820 14:49:27 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 345133568; Write bytes: 346294272; Read time: 26656; Write time: 703 14:49:28 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 30526976; Write bytes: 296551936; Read time: 186; Write time: 1083 14:49:28 INFO - Running post-run listener: _upload_blobber_files 14:49:28 INFO - Blob upload gear active. 14:49:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:49:28 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 14:49:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:49:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:49:28 INFO - (blobuploader) - INFO - Open directory for files ... 14:49:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:49:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:49:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:49:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:49:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:49:29 INFO - (blobuploader) - INFO - Done attempting. 14:49:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:49:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:49:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:49:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:49:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:49:30 INFO - (blobuploader) - INFO - Done attempting. 14:49:30 INFO - (blobuploader) - INFO - Iteration through files over. 14:49:30 INFO - Return code: 0 14:49:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:49:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:49:30 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9cd07e7be20d6296610358297b76f785cd1974cb4aebc4573c5fb102a73325dbffaaa383fe4416a40e7738fc6f98efac61eb665cd70112a80be4a3f1df7bbc13", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c245fa123a9d7de519c3515ca68696b8739c24093a8d0660b34975520c1b64bfee4950e40cac9b8227f2f0500991a4ff132c5d56d3df20edc06a0f0990aae29c"} 14:49:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:49:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:49:30 INFO - Contents: 14:49:30 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9cd07e7be20d6296610358297b76f785cd1974cb4aebc4573c5fb102a73325dbffaaa383fe4416a40e7738fc6f98efac61eb665cd70112a80be4a3f1df7bbc13", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c245fa123a9d7de519c3515ca68696b8739c24093a8d0660b34975520c1b64bfee4950e40cac9b8227f2f0500991a4ff132c5d56d3df20edc06a0f0990aae29c"} 14:49:30 INFO - Running post-run listener: copy_logs_to_upload_dir 14:49:30 INFO - Copying logs to upload dir... 14:49:30 INFO - mkdir: /builds/slave/test/build/upload/logs 14:49:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.486921 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-05-16 14:49:30.525429) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-16 14:49:30.528996) ========= 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.pl8FgYKsdO/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.LfNZnAbM66/Listeners TMPDIR=/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9cd07e7be20d6296610358297b76f785cd1974cb4aebc4573c5fb102a73325dbffaaa383fe4416a40e7738fc6f98efac61eb665cd70112a80be4a3f1df7bbc13", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c245fa123a9d7de519c3515ca68696b8739c24093a8d0660b34975520c1b64bfee4950e40cac9b8227f2f0500991a4ff132c5d56d3df20edc06a0f0990aae29c"} build_url:https://queue.taskcluster.net/v1/task/Q1LVbzKORf61r3aTB3ti-w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011179 build_url: 'https://queue.taskcluster.net/v1/task/Q1LVbzKORf61r3aTB3ti-w/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9cd07e7be20d6296610358297b76f785cd1974cb4aebc4573c5fb102a73325dbffaaa383fe4416a40e7738fc6f98efac61eb665cd70112a80be4a3f1df7bbc13", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c245fa123a9d7de519c3515ca68696b8739c24093a8d0660b34975520c1b64bfee4950e40cac9b8227f2f0500991a4ff132c5d56d3df20edc06a0f0990aae29c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-16 14:49:30.561781) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 14:49:30.562535) ========= 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.pl8FgYKsdO/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.LfNZnAbM66/Listeners TMPDIR=/var/folders/x8/51gh5t9x18561frvys_qxbhw00000w/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.005331 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 14:49:30.616861) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-16 14:49:30.617687) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-16 14:49:30.618451) ========= ========= Total master_lag: 0.22 =========