builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0056 starttime: 1461089834.35 results: success (0) buildid: 20160419093236 builduid: 88923e6739484361bfe03699ddcf109b revision: 45f98cbddc015c5659abe141de0b8fa6bd534051 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-19 11:17:14.346660) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-19 11:17:14.347074) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-19 11:17:14.347346) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-19 11:17:14.375913) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-04-19 11:17:14.376175) ========= 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.zic1XNgTvr/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.At6aEjL8km/Listeners TMPDIR=/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-19 11:17:14-- 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% 42.3M=0s 2016-04-19 11:17:14 (42.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.124833 ========= master_lag: 3.10 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-04-19 11:17:17.598447) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 11:17:17.598732) ========= 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.zic1XNgTvr/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.At6aEjL8km/Listeners TMPDIR=/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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.026507 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 11:17:17.643613) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-19 11:17:17.643990) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 45f98cbddc015c5659abe141de0b8fa6bd534051 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 45f98cbddc015c5659abe141de0b8fa6bd534051 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zic1XNgTvr/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.At6aEjL8km/Listeners TMPDIR=/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-19 11:17:17,720 truncating revision to first 12 chars 2016-04-19 11:17:17,720 Setting DEBUG logging. 2016-04-19 11:17:17,720 attempt 1/10 2016-04-19 11:17:17,720 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/45f98cbddc01?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-19 11:17:19,542 unpacking tar archive at: fx-team-45f98cbddc01/testing/mozharness/ program finished with exit code 0 elapsedTime=2.099277 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-19 11:17:19.760080) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-19 11:17:19.760399) ========= script_repo_revision: 45f98cbddc015c5659abe141de0b8fa6bd534051 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-19 11:17:19.760789) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-19 11:17:19.761069) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-19 11:17:19.774743) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-04-19 11:17:19.775091) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zic1XNgTvr/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.At6aEjL8km/Listeners TMPDIR=/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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 11:17:19 INFO - MultiFileLogger online at 20160419 11:17:19 in /builds/slave/test 11:17:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 11:17:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:17:19 INFO - {'append_to_log': False, 11:17:19 INFO - 'base_work_dir': '/builds/slave/test', 11:17:19 INFO - 'blob_upload_branch': 'Fx-Team', 11:17:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:17:19 INFO - 'branch': 'Fx-Team', 11:17:19 INFO - 'buildbot_json_path': 'buildprops.json', 11:17:19 INFO - 'config_files': ('talos/mac_config.py',), 11:17:19 INFO - 'default_actions': ('clobber', 11:17:19 INFO - 'read-buildbot-config', 11:17:19 INFO - 'download-and-extract', 11:17:19 INFO - 'populate-webroot', 11:17:19 INFO - 'create-virtualenv', 11:17:19 INFO - 'install', 11:17:19 INFO - 'run-tests'), 11:17:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:17:19 INFO - 'download_minidump_stackwalk': True, 11:17:19 INFO - 'download_symbols': 'ondemand', 11:17:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:17:19 INFO - 'tooltool.py': '/tools/tooltool.py', 11:17:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:17:19 INFO - '/tools/misc-python/virtualenv.py')}, 11:17:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:17:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:17:19 INFO - 'installer_path': 'installer.exe', 11:17:19 INFO - 'log_level': 'info', 11:17:19 INFO - 'log_name': 'talos', 11:17:19 INFO - 'log_to_console': True, 11:17:19 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:17:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:17:19 INFO - 'opt_config_files': (), 11:17:19 INFO - 'pip_index': False, 11:17:19 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:17:19 INFO - 'cmd': ('bash', 11:17:19 INFO - '-c', 11:17:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:17:19 INFO - 'enabled': True, 11:17:19 INFO - 'halt_on_failure': False, 11:17:19 INFO - 'name': 'check_screen_resolution'},), 11:17:19 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:17:19 INFO - 'cmd': ('bash', 11:17:19 INFO - '-c', 11:17:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:17:19 INFO - 'enabled': True, 11:17:19 INFO - 'halt_on_failure': False, 11:17:19 INFO - 'name': 'check_screen_resolution'},), 11:17:19 INFO - 'run_cmd_checks_enabled': True, 11:17:19 INFO - 'sps_profile': False, 11:17:19 INFO - 'sps_profile_interval': 0, 11:17:19 INFO - 'suite': 'dromaeojs', 11:17:19 INFO - 'system_bits': '32', 11:17:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:17:19 INFO - 'title': 't-yosemite-r7-0056', 11:17:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:17:19 INFO - 'use_talos_json': True, 11:17:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:17:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:17:19 INFO - 'work_dir': 'build'} 11:17:19 INFO - ##### 11:17:19 INFO - ##### Running clobber step. 11:17:19 INFO - ##### 11:17:19 INFO - Running pre-action listener: _resource_record_pre_action 11:17:19 INFO - Running main action method: clobber 11:17:19 INFO - rmtree: /builds/slave/test/build 11:17:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:17:21 INFO - Running post-action listener: _resource_record_post_action 11:17:21 INFO - ##### 11:17:21 INFO - ##### Running read-buildbot-config step. 11:17:21 INFO - ##### 11:17:21 INFO - Running pre-action listener: _resource_record_pre_action 11:17:21 INFO - Running main action method: read_buildbot_config 11:17:21 INFO - Using buildbot properties: 11:17:21 INFO - { 11:17:21 INFO - "project": "", 11:17:21 INFO - "product": "firefox", 11:17:21 INFO - "script_repo_revision": "production", 11:17:21 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 11:17:21 INFO - "repository": "", 11:17:21 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 11:17:21 INFO - "buildid": "20160419093236", 11:17:21 INFO - "pgo_build": "False", 11:17:21 INFO - "basedir": "/builds/slave/test", 11:17:21 INFO - "buildnumber": 638, 11:17:21 INFO - "slavename": "t-yosemite-r7-0056", 11:17:21 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 11:17:21 INFO - "platform": "yosemite_r7", 11:17:21 INFO - "scheduler": "tests-fx-team-macosx64-talos", 11:17:21 INFO - "branch": "fx-team", 11:17:21 INFO - "repo_path": "integration/fx-team", 11:17:21 INFO - "revision": "45f98cbddc015c5659abe141de0b8fa6bd534051", 11:17:21 INFO - "stage_platform": "macosx64", 11:17:21 INFO - "builduid": "88923e6739484361bfe03699ddcf109b", 11:17:21 INFO - "slavebuilddir": "test" 11:17:21 INFO - } 11:17:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/SFZbYUUXTjeC3HwIGcnm_w/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 11:17:21 INFO - Running post-action listener: _resource_record_post_action 11:17:21 INFO - ##### 11:17:21 INFO - ##### Running download-and-extract step. 11:17:21 INFO - ##### 11:17:21 INFO - Running pre-action listener: _resource_record_pre_action 11:17:21 INFO - Running main action method: download_and_extract 11:17:21 INFO - mkdir: /builds/slave/test/build/tests 11:17:21 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')]} 11:17:21 INFO - https://queue.taskcluster.net/v1/task/SFZbYUUXTjeC3HwIGcnm_w/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 11:17:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SFZbYUUXTjeC3HwIGcnm_w/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 11:17:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SFZbYUUXTjeC3HwIGcnm_w/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 11:17:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SFZbYUUXTjeC3HwIGcnm_w/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 11:17:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SFZbYUUXTjeC3HwIGcnm_w/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 11:17:22 INFO - Downloaded 1199 bytes. 11:17:22 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 11:17:22 INFO - Using the following test package requirements: 11:17:22 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 11:17:22 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 11:17:22 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 11:17:22 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 11:17:22 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 11:17:22 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 11:17:22 INFO - u'jsshell-mac.zip'], 11:17:22 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 11:17:22 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 11:17:22 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 11:17:22 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 11:17:22 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 11:17:22 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 11:17:22 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 11:17:22 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 11:17:22 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 11:17:22 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 11:17:22 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 11:17:22 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:17:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:17:22 INFO - https://queue.taskcluster.net/v1/task/SFZbYUUXTjeC3HwIGcnm_w/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 11:17:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SFZbYUUXTjeC3HwIGcnm_w/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 11:17:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SFZbYUUXTjeC3HwIGcnm_w/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 11:17:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SFZbYUUXTjeC3HwIGcnm_w/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 11:17:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SFZbYUUXTjeC3HwIGcnm_w/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:17:24 INFO - Downloaded 18039356 bytes. 11:17:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:17:24 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 11:17:27 INFO - Return code: 0 11:17:27 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 11:17: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')]} 11:17:27 INFO - https://queue.taskcluster.net/v1/task/SFZbYUUXTjeC3HwIGcnm_w/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 11:17:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SFZbYUUXTjeC3HwIGcnm_w/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 11:17:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SFZbYUUXTjeC3HwIGcnm_w/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 11:17:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SFZbYUUXTjeC3HwIGcnm_w/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 11:17:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SFZbYUUXTjeC3HwIGcnm_w/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:17:28 INFO - Downloaded 18039356 bytes. 11:17:28 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:17:28 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 11:17:32 INFO - Return code: 0 11:17: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')]} 11:17:32 INFO - https://queue.taskcluster.net/v1/task/SFZbYUUXTjeC3HwIGcnm_w/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 11:17:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SFZbYUUXTjeC3HwIGcnm_w/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 11:17:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SFZbYUUXTjeC3HwIGcnm_w/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 11:17:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SFZbYUUXTjeC3HwIGcnm_w/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 11:17:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SFZbYUUXTjeC3HwIGcnm_w/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 11:17:33 INFO - Downloaded 10845952 bytes. 11:17:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:17:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 11:17:33 INFO - Return code: 0 11:17:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:17:33 INFO - https://queue.taskcluster.net/v1/task/SFZbYUUXTjeC3HwIGcnm_w/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 11:17:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SFZbYUUXTjeC3HwIGcnm_w/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 11:17:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SFZbYUUXTjeC3HwIGcnm_w/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 11:17:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SFZbYUUXTjeC3HwIGcnm_w/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 11:17:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SFZbYUUXTjeC3HwIGcnm_w/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 11:17:37 INFO - Downloaded 109939917 bytes. 11:17:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SFZbYUUXTjeC3HwIGcnm_w/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 11:17:37 INFO - mkdir: /builds/slave/test/properties 11:17:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:17:37 INFO - Writing to file /builds/slave/test/properties/build_url 11:17:37 INFO - Contents: 11:17:37 INFO - build_url:https://queue.taskcluster.net/v1/task/SFZbYUUXTjeC3HwIGcnm_w/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 11:17:38 INFO - Running post-action listener: _resource_record_post_action 11:17:38 INFO - Running post-action listener: set_extra_try_arguments 11:17:38 INFO - ##### 11:17:38 INFO - ##### Running populate-webroot step. 11:17:38 INFO - ##### 11:17:38 INFO - Running pre-action listener: _resource_record_pre_action 11:17:38 INFO - Running main action method: populate_webroot 11:17:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:17:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:17:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:17:38 INFO - '--tppagecycles', 11:17:38 INFO - '7'], 11:17:38 INFO - 'tests': ['tsvgm']}}, 11:17:38 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:17:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:17:38 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:17:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:17:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:17:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:17:38 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:17:38 INFO - 'talos_options': ['--disable-e10s'], 11:17:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:17:38 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:17:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:17:38 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:17:38 INFO - 'talos_options': ['--disable-e10s'], 11:17:38 INFO - 'tests': ['damp', 'tps']}, 11:17:38 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:17:38 INFO - 'tests': ['damp', 'tps']}, 11:17:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:17:38 INFO - 'tests': ['dromaeo_dom']}, 11:17:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:17:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:17:38 INFO - 'tests': ['a11yr', 11:17:38 INFO - 'ts_paint', 11:17:38 INFO - 'tpaint', 11:17:38 INFO - 'sessionrestore', 11:17:38 INFO - 'sessionrestore_no_auto_restore', 11:17:38 INFO - 'tabpaint']}, 11:17:38 INFO - 'other-e10s': {'tests': ['a11yr', 11:17:38 INFO - 'ts_paint', 11:17:38 INFO - 'tpaint', 11:17:38 INFO - 'sessionrestore', 11:17:38 INFO - 'sessionrestore_no_auto_restore', 11:17:38 INFO - 'tabpaint']}, 11:17:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:17:38 INFO - 'ts_paint', 11:17:38 INFO - 'tpaint', 11:17:38 INFO - 'sessionrestore', 11:17:38 INFO - 'sessionrestore_no_auto_restore', 11:17:38 INFO - 'tabpaint']}, 11:17:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:17:38 INFO - 'ts_paint', 11:17:38 INFO - 'tpaint', 11:17:38 INFO - 'sessionrestore', 11:17:38 INFO - 'sessionrestore_no_auto_restore', 11:17:38 INFO - 'tabpaint']}, 11:17:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:17:38 INFO - 'tests': ['a11yr', 11:17:38 INFO - 'ts_paint', 11:17:38 INFO - 'tpaint', 11:17:38 INFO - 'sessionrestore', 11:17:38 INFO - 'sessionrestore_no_auto_restore', 11:17:38 INFO - 'tabpaint']}, 11:17:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:17:38 INFO - 'tests': ['a11yr', 11:17:38 INFO - 'ts_paint', 11:17:38 INFO - 'tpaint', 11:17:38 INFO - 'sessionrestore', 11:17:38 INFO - 'sessionrestore_no_auto_restore', 11:17:38 INFO - 'tabpaint']}, 11:17:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:17:38 INFO - 'tests': ['tsvgx', 11:17:38 INFO - 'tsvgr_opacity', 11:17:38 INFO - 'tart', 11:17:38 INFO - 'tscrollx', 11:17:38 INFO - 'cart']}, 11:17:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:17:38 INFO - 'tsvgr_opacity', 11:17:38 INFO - 'tart', 11:17:38 INFO - 'tscrollx', 11:17:38 INFO - 'cart']}, 11:17:38 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:17:38 INFO - 'talos_options': ['--disable-e10s'], 11:17:38 INFO - 'tests': ['tp5o']}, 11:17:38 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:17:38 INFO - 'tests': ['tp5o']}, 11:17:38 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:17:38 INFO - 'talos_options': ['--disable-e10s', 11:17:38 INFO - '--xperf_path', 11:17:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:17:38 INFO - 'tests': ['tp5n']}, 11:17:38 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:17:38 INFO - 'talos_options': ['--xperf_path', 11:17:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:17:38 INFO - 'tests': ['tp5n']}}, 11:17:38 INFO - 'talos.zip': {'path': '', 11:17:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:17:38 INFO - Running post-action listener: _resource_record_post_action 11:17:38 INFO - ##### 11:17:38 INFO - ##### Running create-virtualenv step. 11:17:38 INFO - ##### 11:17:38 INFO - Running pre-action listener: _resource_record_pre_action 11:17:38 INFO - Running main action method: create_virtualenv 11:17:38 INFO - Creating virtualenv /builds/slave/test/build/venv 11:17:38 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 11:17:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:17:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:17:38 INFO - Using real prefix '/tools/python27' 11:17:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:17:39 INFO - Installing distribute.............................................................................................................................................................................................done. 11:17:42 INFO - Installing pip.................done. 11:17:42 INFO - Return code: 0 11:17:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:17:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:17:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:17:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:17:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:17:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:17:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:17:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cdeda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdfd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe95248c030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc8ce00>, '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 0x10cab5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc87b20>, '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.zic1XNgTvr/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.At6aEjL8km/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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 11:17:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:17:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:17:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zic1XNgTvr/Render', 11:17:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:17:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:17:42 INFO - 'HOME': '/Users/cltbld', 11:17:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:17:42 INFO - 'LOGNAME': 'cltbld', 11:17:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:17:42 INFO - 'MOZ_NO_REMOTE': '1', 11:17:42 INFO - 'NO_EM_RESTART': '1', 11:17:42 INFO - 'PAGER': '/bin/cat', 11:17:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:17:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:17:42 INFO - 'PWD': '/builds/slave/test', 11:17:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:17:42 INFO - 'SHELL': '/bin/bash', 11:17:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.At6aEjL8km/Listeners', 11:17:42 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 11:17:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:17:42 INFO - 'USER': 'cltbld', 11:17:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:17:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:17:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:17:42 INFO - 'XPC_FLAGS': '0x0', 11:17:42 INFO - 'XPC_SERVICE_NAME': '0', 11:17:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:17:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:17:42 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 11:17:42 INFO - Cleaning up... 11:17:42 INFO - Return code: 0 11:17:42 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 11:17:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:17:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:17:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:17:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:17:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:17:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:17:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10cdeda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdfd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe95248c030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc8ce00>, '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 0x10cab5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc87b20>, '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.zic1XNgTvr/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.At6aEjL8km/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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 11:17:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 11:17:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 11:17:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zic1XNgTvr/Render', 11:17:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:17:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:17:42 INFO - 'HOME': '/Users/cltbld', 11:17:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:17:42 INFO - 'LOGNAME': 'cltbld', 11:17:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:17:42 INFO - 'MOZ_NO_REMOTE': '1', 11:17:42 INFO - 'NO_EM_RESTART': '1', 11:17:42 INFO - 'PAGER': '/bin/cat', 11:17:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:17:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:17:42 INFO - 'PWD': '/builds/slave/test', 11:17:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:17:42 INFO - 'SHELL': '/bin/bash', 11:17:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.At6aEjL8km/Listeners', 11:17:42 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 11:17:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:17:42 INFO - 'USER': 'cltbld', 11:17:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:17:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:17:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:17:42 INFO - 'XPC_FLAGS': '0x0', 11:17:42 INFO - 'XPC_SERVICE_NAME': '0', 11:17:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:17:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:17:42 INFO - Downloading/unpacking psutil>=0.7.1 11:17:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:17:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:17:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:17:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:17:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:17:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:17:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:17:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:17:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:17:45 INFO - Installing collected packages: psutil 11:17:45 INFO - Running setup.py install for psutil 11:17:45 INFO - building 'psutil._psutil_osx' extension 11:17:45 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 11:17:45 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 11:17:46 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 11:17:46 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 11:17:46 INFO - building 'psutil._psutil_posix' extension 11:17:46 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 11:17:46 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:17:46 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:17:46 INFO - ^ 11:17:46 INFO - 1 warning generated. 11:17:46 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 11:17:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:17:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:17:46 INFO - Successfully installed psutil 11:17:46 INFO - Cleaning up... 11:17:46 INFO - Return code: 0 11:17:46 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 11:17:46 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')]} 11:17:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:17:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:17:46 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')]} 11:17:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:17:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:17:46 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 0x10cdeda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdfd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe95248c030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc8ce00>, '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 0x10cab5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc87b20>, '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.zic1XNgTvr/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.At6aEjL8km/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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 11:17:46 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 11:17:46 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 11:17:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zic1XNgTvr/Render', 11:17:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:17:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:17:46 INFO - 'HOME': '/Users/cltbld', 11:17:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:17:46 INFO - 'LOGNAME': 'cltbld', 11:17:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:17:46 INFO - 'MOZ_NO_REMOTE': '1', 11:17:46 INFO - 'NO_EM_RESTART': '1', 11:17:46 INFO - 'PAGER': '/bin/cat', 11:17:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:17:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:17:46 INFO - 'PWD': '/builds/slave/test', 11:17:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:17:46 INFO - 'SHELL': '/bin/bash', 11:17:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.At6aEjL8km/Listeners', 11:17:46 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 11:17:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:17:46 INFO - 'USER': 'cltbld', 11:17:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:17:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:17:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:17:46 INFO - 'XPC_FLAGS': '0x0', 11:17:46 INFO - 'XPC_SERVICE_NAME': '0', 11:17:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:17:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:17:46 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 11:17:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:17:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:17:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:17:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:17:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:17:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:17:49 INFO - Downloading mozsystemmonitor-0.0.tar.gz 11:17:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:17:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 11:17:49 INFO - Installing collected packages: mozsystemmonitor 11:17:49 INFO - Running setup.py install for mozsystemmonitor 11:17:49 INFO - Successfully installed mozsystemmonitor 11:17:49 INFO - Cleaning up... 11:17:49 INFO - Return code: 0 11:17:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:17:49 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')]} 11:17:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:17:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:17:49 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')]} 11:17:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:17:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:17:49 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 0x10cdeda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdfd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe95248c030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc8ce00>, '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 0x10cab5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc87b20>, '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.zic1XNgTvr/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.At6aEjL8km/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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 11:17:49 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 11:17:49 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 11:17:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zic1XNgTvr/Render', 11:17:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:17:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:17:49 INFO - 'HOME': '/Users/cltbld', 11:17:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:17:49 INFO - 'LOGNAME': 'cltbld', 11:17:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:17:49 INFO - 'MOZ_NO_REMOTE': '1', 11:17:49 INFO - 'NO_EM_RESTART': '1', 11:17:49 INFO - 'PAGER': '/bin/cat', 11:17:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:17:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:17:49 INFO - 'PWD': '/builds/slave/test', 11:17:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:17:49 INFO - 'SHELL': '/bin/bash', 11:17:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.At6aEjL8km/Listeners', 11:17:49 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 11:17:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:17:49 INFO - 'USER': 'cltbld', 11:17:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:17:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:17:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:17:49 INFO - 'XPC_FLAGS': '0x0', 11:17:49 INFO - 'XPC_SERVICE_NAME': '0', 11:17:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:17:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:17:49 INFO - Downloading/unpacking blobuploader==1.2.4 11:17:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:17:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:17:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:17:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:17:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:17:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:17:52 INFO - Downloading blobuploader-1.2.4.tar.gz 11:17:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:17:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:17: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 11:17: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 11:17: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 11:17: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 11:17: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 11:17: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 11:17:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:17:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:17: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 11:17: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 11:17: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 11:17: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 11:17: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 11:17: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 11:17:53 INFO - Downloading docopt-0.6.1.tar.gz 11:17:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:17:53 INFO - Installing collected packages: blobuploader, requests, docopt 11:17:53 INFO - Running setup.py install for blobuploader 11:17:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:17:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:17:53 INFO - Running setup.py install for requests 11:17:54 INFO - Running setup.py install for docopt 11:17:54 INFO - Successfully installed blobuploader requests docopt 11:17:54 INFO - Cleaning up... 11:17:54 INFO - Return code: 0 11:17:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:17:54 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')]} 11:17:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:17:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:17:54 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')]} 11:17:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:17:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:17:54 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 0x10cdeda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdfd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe95248c030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc8ce00>, '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 0x10cab5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc87b20>, '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.zic1XNgTvr/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.At6aEjL8km/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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 11:17:54 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 11:17:54 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 11:17:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zic1XNgTvr/Render', 11:17:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:17:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:17:54 INFO - 'HOME': '/Users/cltbld', 11:17:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:17:54 INFO - 'LOGNAME': 'cltbld', 11:17:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:17:54 INFO - 'MOZ_NO_REMOTE': '1', 11:17:54 INFO - 'NO_EM_RESTART': '1', 11:17:54 INFO - 'PAGER': '/bin/cat', 11:17:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:17:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:17:54 INFO - 'PWD': '/builds/slave/test', 11:17:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:17:54 INFO - 'SHELL': '/bin/bash', 11:17:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.At6aEjL8km/Listeners', 11:17:54 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 11:17:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:17:54 INFO - 'USER': 'cltbld', 11:17:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:17:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:17:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:17:54 INFO - 'XPC_FLAGS': '0x0', 11:17:54 INFO - 'XPC_SERVICE_NAME': '0', 11:17:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:17:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:17:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:17:54 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-WQKXCs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:17:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:17:54 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-J2RKIc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:17:55 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-dJmQTt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:17:55 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-Ax39q2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:17:55 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-Ys4RWT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:17:55 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-3bBGk6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:17:55 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-MCOE7f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:17:55 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-v6YAa5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:17:55 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-EITfbz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:17:55 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-KvSdH2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:17:55 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-Gae4Hl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:17:55 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-8JTiAz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:17:56 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-obCYiI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:17:56 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-8n4oLs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:17:56 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-VK8l2x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:17:56 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-Q2GlaG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:17:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:17:56 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-QUVlSb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:17:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:17:56 INFO - Running setup.py install for manifestparser 11:17:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:17:56 INFO - Running setup.py install for mozcrash 11:17:56 INFO - Running setup.py install for mozdebug 11:17:56 INFO - Running setup.py install for mozdevice 11:17:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:17:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:17:57 INFO - Running setup.py install for mozfile 11:17:57 INFO - Running setup.py install for mozhttpd 11:17:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:17:57 INFO - Running setup.py install for mozinfo 11:17:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:17:57 INFO - Running setup.py install for mozInstall 11:17:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:17:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:17:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:17:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:17:57 INFO - Running setup.py install for mozleak 11:17:57 INFO - Running setup.py install for mozlog 11:17:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:17:58 INFO - Running setup.py install for moznetwork 11:17:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:17:58 INFO - Running setup.py install for mozprocess 11:17:58 INFO - Running setup.py install for mozprofile 11:17:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:17:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:17:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:17:58 INFO - Running setup.py install for mozrunner 11:17:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:17:58 INFO - Running setup.py install for mozscreenshot 11:17:58 INFO - Running setup.py install for moztest 11:17:58 INFO - Running setup.py install for mozversion 11:17:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:17:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:17:59 INFO - Cleaning up... 11:17:59 INFO - Return code: 0 11:17:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:17:59 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')]} 11:17:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:17:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:17:59 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')]} 11:17:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:17:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:17:59 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 0x10cdeda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdfd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe95248c030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc8ce00>, '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 0x10cab5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc87b20>, '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.zic1XNgTvr/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.At6aEjL8km/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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 11:17:59 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 11:17:59 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 11:17:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zic1XNgTvr/Render', 11:17:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:17:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:17:59 INFO - 'HOME': '/Users/cltbld', 11:17:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:17:59 INFO - 'LOGNAME': 'cltbld', 11:17:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:17:59 INFO - 'MOZ_NO_REMOTE': '1', 11:17:59 INFO - 'NO_EM_RESTART': '1', 11:17:59 INFO - 'PAGER': '/bin/cat', 11:17:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:17:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:17:59 INFO - 'PWD': '/builds/slave/test', 11:17:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:17:59 INFO - 'SHELL': '/bin/bash', 11:17:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.At6aEjL8km/Listeners', 11:17:59 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 11:17:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:17:59 INFO - 'USER': 'cltbld', 11:17:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:17:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:17:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:17:59 INFO - 'XPC_FLAGS': '0x0', 11:17:59 INFO - 'XPC_SERVICE_NAME': '0', 11:17:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:17:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:17:59 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-BULhU7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:17:59 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)) 11:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:17:59 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-Ktrfzv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:17:59 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)) 11:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:17:59 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-1298TY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:17:59 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)) 11:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:17:59 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-HYfxAq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:17:59 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)) 11:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:17:59 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-joBNyX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:17:59 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)) 11:17:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:17:59 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-svhKJG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:18:00 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)) 11:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:18:00 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-LHBzTQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:18:00 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)) 11:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:18:00 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-XxCSMP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:18:00 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)) 11:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:18:00 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-s9RVbD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:18:00 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)) 11:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:18:00 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-xoPY8b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:18:00 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)) 11:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:18:00 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-cvVDzH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:18:00 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)) 11:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:18:00 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-04IMN0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:18:00 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)) 11:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:18:00 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-jTFERU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:18:00 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)) 11:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:18:00 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-TutSeH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:18:00 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)) 11:18:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:18:00 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-faDc6k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:18:01 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)) 11:18:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:18:01 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-pGBFuy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:18:01 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)) 11:18:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:18:01 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-iVwZVn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:18:01 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)) 11:18:01 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)) 11:18:01 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)) 11:18:01 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)) 11:18:01 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)) 11:18:01 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)) 11:18:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:18:01 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 11:18:01 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 11:18:01 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 11:18:01 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 11:18:01 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 11:18:01 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 11:18:03 INFO - Downloading blessings-1.6.tar.gz 11:18:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:18:04 INFO - Installing collected packages: blessings 11:18:04 INFO - Running setup.py install for blessings 11:18:04 INFO - Successfully installed blessings 11:18:04 INFO - Cleaning up... 11:18:04 INFO - Return code: 0 11:18:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:18:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:18:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:18:04 INFO - Reading from file tmpfile_stdout 11:18:04 INFO - Current package versions: 11:18:04 INFO - blessings == 1.6 11:18:04 INFO - blobuploader == 1.2.4 11:18:04 INFO - docopt == 0.6.1 11:18:04 INFO - manifestparser == 1.1 11:18:04 INFO - mozInstall == 1.12 11:18:04 INFO - mozcrash == 0.17 11:18:04 INFO - mozdebug == 0.1 11:18:04 INFO - mozdevice == 0.48 11:18:04 INFO - mozfile == 1.2 11:18:04 INFO - mozhttpd == 0.7 11:18:04 INFO - mozinfo == 0.9 11:18:04 INFO - mozleak == 0.1 11:18:04 INFO - mozlog == 3.1 11:18:04 INFO - moznetwork == 0.27 11:18:04 INFO - mozprocess == 0.22 11:18:04 INFO - mozprofile == 0.28 11:18:04 INFO - mozrunner == 6.11 11:18:04 INFO - mozscreenshot == 0.1 11:18:04 INFO - mozsystemmonitor == 0.0 11:18:04 INFO - moztest == 0.7 11:18:04 INFO - mozversion == 1.4 11:18:04 INFO - psutil == 3.1.1 11:18:04 INFO - requests == 1.2.3 11:18:04 INFO - wsgiref == 0.1.2 11:18:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:18: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')]} 11:18:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:18:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:18: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')]} 11:18:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:18:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:18:04 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 0x10cdeda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdfd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe95248c030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc8ce00>, '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 0x10cab5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc87b20>, '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.zic1XNgTvr/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.At6aEjL8km/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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 11:18:04 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 11:18:04 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 11:18:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zic1XNgTvr/Render', 11:18:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:18:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:18:04 INFO - 'HOME': '/Users/cltbld', 11:18:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:18:04 INFO - 'LOGNAME': 'cltbld', 11:18:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:18:04 INFO - 'MOZ_NO_REMOTE': '1', 11:18:04 INFO - 'NO_EM_RESTART': '1', 11:18:04 INFO - 'PAGER': '/bin/cat', 11:18:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:18:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:18:04 INFO - 'PWD': '/builds/slave/test', 11:18:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:18:04 INFO - 'SHELL': '/bin/bash', 11:18:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.At6aEjL8km/Listeners', 11:18:04 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 11:18:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:18:04 INFO - 'USER': 'cltbld', 11:18:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:18:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:18:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:18:04 INFO - 'XPC_FLAGS': '0x0', 11:18:04 INFO - 'XPC_SERVICE_NAME': '0', 11:18:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:18:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:18:04 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)) 11:18:04 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)) 11:18:04 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)) 11:18:04 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)) 11:18:04 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)) 11:18:04 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)) 11:18:04 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)) 11:18:04 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)) 11:18:04 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)) 11:18:04 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)) 11:18:04 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)) 11:18:04 INFO - Cleaning up... 11:18:05 INFO - Return code: 0 11:18:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:18:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:18:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:18:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:18:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:18:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:18:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:18:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cdeda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdfd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe95248c030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc8ce00>, '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 0x10cab5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc87b20>, '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.zic1XNgTvr/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.At6aEjL8km/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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 11:18:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:18:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:18:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zic1XNgTvr/Render', 11:18:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:18:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:18:05 INFO - 'HOME': '/Users/cltbld', 11:18:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:18:05 INFO - 'LOGNAME': 'cltbld', 11:18:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:18:05 INFO - 'MOZ_NO_REMOTE': '1', 11:18:05 INFO - 'NO_EM_RESTART': '1', 11:18:05 INFO - 'PAGER': '/bin/cat', 11:18:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:18:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:18:05 INFO - 'PWD': '/builds/slave/test', 11:18:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:18:05 INFO - 'SHELL': '/bin/bash', 11:18:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.At6aEjL8km/Listeners', 11:18:05 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 11:18:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:18:05 INFO - 'USER': 'cltbld', 11:18:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:18:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:18:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:18:05 INFO - 'XPC_FLAGS': '0x0', 11:18:05 INFO - 'XPC_SERVICE_NAME': '0', 11:18:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:18:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:18:05 INFO - Downloading/unpacking jsonschema 11:18:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:18:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:18:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:18:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:18:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:18:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:18:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:18:07 INFO - Downloading/unpacking functools32 (from jsonschema) 11:18:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:18:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:18:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:18:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:18:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:18:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:18:08 INFO - Downloading functools32-3.2.3-2.tar.gz 11:18:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:18:08 INFO - warning: no files found matching '*.txt' 11:18:08 INFO - no previously-included directories found matching 'build' 11:18:08 INFO - no previously-included directories found matching 'dist' 11:18:08 INFO - no previously-included directories found matching '.git*' 11:18:08 INFO - Installing collected packages: jsonschema, functools32 11:18:08 INFO - Running setup.py install for functools32 11:18:08 INFO - warning: no files found matching '*.txt' 11:18:08 INFO - no previously-included directories found matching 'build' 11:18:08 INFO - no previously-included directories found matching 'dist' 11:18:08 INFO - no previously-included directories found matching '.git*' 11:18:08 INFO - Successfully installed jsonschema functools32 11:18:08 INFO - Cleaning up... 11:18:08 INFO - Return code: 0 11:18:08 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 11:18:08 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')]} 11:18:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:18:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:18:08 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')]} 11:18:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:18:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:18:08 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 0x10cdeda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdfd420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe95248c030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cc8ce00>, '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 0x10cab5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc87b20>, '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.zic1XNgTvr/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.At6aEjL8km/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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 11:18:08 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 11:18:08 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 11:18:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zic1XNgTvr/Render', 11:18:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:18:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:18:08 INFO - 'HOME': '/Users/cltbld', 11:18:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:18:08 INFO - 'LOGNAME': 'cltbld', 11:18:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:18:08 INFO - 'MOZ_NO_REMOTE': '1', 11:18:08 INFO - 'NO_EM_RESTART': '1', 11:18:08 INFO - 'PAGER': '/bin/cat', 11:18:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:18:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:18:08 INFO - 'PWD': '/builds/slave/test', 11:18:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:18:08 INFO - 'SHELL': '/bin/bash', 11:18:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.At6aEjL8km/Listeners', 11:18:08 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 11:18:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:18:08 INFO - 'USER': 'cltbld', 11:18:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:18:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:18:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:18:08 INFO - 'XPC_FLAGS': '0x0', 11:18:08 INFO - 'XPC_SERVICE_NAME': '0', 11:18:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:18:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:18:08 INFO - Downloading/unpacking flake8 11:18:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:18:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:18:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:18:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:18:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:18:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:18:11 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 11:18:11 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 11:18:11 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 11:18:11 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 11:18:11 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 11:18:11 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 11:18:11 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 11:18:11 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 11:18:11 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 11:18:11 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 11:18:11 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 11:18:11 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 11:18:11 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 11:18:11 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 11:18:11 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 11:18:11 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 11:18:11 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 11:18:11 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 11:18:11 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 11:18:11 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 11:18:11 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 11:18:11 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 11:18:11 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 11:18:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 11:18:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:18:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:18:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:18:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:18:12 INFO - no previously-included directories found matching 'docs/_build' 11:18:12 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 11:18:12 INFO - Running setup.py install for pep8 11:18:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:18:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:18:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:18:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:18:12 INFO - no previously-included directories found matching 'docs/_build' 11:18:12 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 11:18:12 INFO - Successfully installed flake8 mccabe pyflakes pep8 11:18:12 INFO - Cleaning up... 11:18:12 INFO - Return code: 0 11:18:12 INFO - Running post-action listener: _resource_record_post_action 11:18:12 INFO - Running post-action listener: _start_resource_monitoring 11:18:12 INFO - Starting resource monitoring. 11:18:12 INFO - ##### 11:18:12 INFO - ##### Running install step. 11:18:12 INFO - ##### 11:18:12 INFO - Running pre-action listener: _resource_record_pre_action 11:18:12 INFO - Running main action method: install 11:18:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:18:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:18:13 INFO - Reading from file tmpfile_stdout 11:18:13 INFO - Detecting whether we're running mozinstall >=1.0... 11:18:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:18:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:18:13 INFO - Reading from file tmpfile_stdout 11:18:13 INFO - Output received: 11:18:13 INFO - Usage: mozinstall [options] installer 11:18:13 INFO - Options: 11:18:13 INFO - -h, --help show this help message and exit 11:18:13 INFO - -d DEST, --destination=DEST 11:18:13 INFO - Directory to install application into. [default: 11:18:13 INFO - "/builds/slave/test"] 11:18:13 INFO - --app=APP Application being installed. [default: firefox] 11:18:13 INFO - mkdir: /builds/slave/test/build/application 11:18:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 11:18:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 11:18:46 INFO - Reading from file tmpfile_stdout 11:18:46 INFO - Output received: 11:18:46 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:18:46 INFO - Running post-action listener: _resource_record_post_action 11:18:46 INFO - ##### 11:18:46 INFO - ##### Running run-tests step. 11:18:46 INFO - ##### 11:18:46 INFO - Running pre-action listener: _resource_record_pre_action 11:18:46 INFO - Running main action method: run_tests 11:18:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:18:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:18:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:18:46 INFO - 2016-04-19 11:18:46.964 screenresolution[1650:7895] starting screenresolution argv=screenresolution get 11:18:46 INFO - 2016-04-19 11:18:46.966 screenresolution[1650:7895] Display 0: 1600x1200x32@60 11:18:46 INFO - 2016-04-19 11:18:46.972 screenresolution[1651:7897] starting screenresolution argv=screenresolution list 11:18:46 INFO - Available Modes on Display 0 11:18:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:18:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:18:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:18:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:18:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:18:47 INFO - Intel Iris: 11:18:47 INFO - Chipset Model: Intel Iris 11:18:47 INFO - Type: GPU 11:18:47 INFO - Bus: Built-In 11:18:47 INFO - VRAM (Dynamic, Max): 1536 MB 11:18:47 INFO - Vendor: Intel (0x8086) 11:18:47 INFO - Device ID: 0x0a2e 11:18:47 INFO - Revision ID: 0x0009 11:18:47 INFO - Displays: 11:18:47 INFO - Display: 11:18:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:18:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:18:47 INFO - Display Serial Number: 1600x1200 60 11:18:47 INFO - Main Display: Yes 11:18:47 INFO - Mirror: Off 11:18:47 INFO - Online: Yes 11:18:47 INFO - Rotation: Supported 11:18:47 INFO - Return code: 0 11:18:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:18:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:18:47 INFO - Python 2.7.3 11:18:47 INFO - Return code: 0 11:18:47 INFO - grabbing minidump binary from tooltool 11:18: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')]} 11:18:47 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 0x10cc8ce00>, '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 0x10cab5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cc87b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:18:47 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 11:18:47 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 11:18:47 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:18:47 INFO - Return code: 0 11:18:47 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:18:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:18:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:18:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:18:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:18:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 11:18:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 11:18:48 INFO - Return code: 0 11:18:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SFZbYUUXTjeC3HwIGcnm_w/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0056', '--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 11:18:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SFZbYUUXTjeC3HwIGcnm_w/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0056 --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 11:18:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zic1XNgTvr/Render', 11:18:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:18:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:18:48 INFO - 'HOME': '/Users/cltbld', 11:18:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:18:48 INFO - 'LOGNAME': 'cltbld', 11:18:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:18:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:18:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:18:48 INFO - 'MOZ_NO_REMOTE': '1', 11:18:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:18:48 INFO - 'NO_EM_RESTART': '1', 11:18:48 INFO - 'PAGER': '/bin/cat', 11:18:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:18:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:18:48 INFO - 'PWD': '/builds/slave/test', 11:18:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:18:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:18:48 INFO - 'SHELL': '/bin/bash', 11:18:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.At6aEjL8km/Listeners', 11:18:48 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 11:18:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:18:48 INFO - 'USER': 'cltbld', 11:18:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:18:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:18:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:18:48 INFO - 'XPC_FLAGS': '0x0', 11:18:48 INFO - 'XPC_SERVICE_NAME': '0', 11:18:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:18:48 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SFZbYUUXTjeC3HwIGcnm_w/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0056', '--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 11:18:48 INFO - mozversion application_buildid: 20160419093236 11:18:48 INFO - mozversion application_changeset: 45f98cbddc015c5659abe141de0b8fa6bd534051 11:18:48 INFO - mozversion application_display_name: Nightly 11:18:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:18:48 INFO - mozversion application_name: Firefox 11:18:48 INFO - mozversion application_remotingname: firefox 11:18:48 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 11:18:48 INFO - mozversion application_vendor: Mozilla 11:18:48 INFO - mozversion application_version: 48.0a1 11:18:48 INFO - mozversion platform_buildid: 20160419093236 11:18:48 INFO - mozversion platform_changeset: 45f98cbddc015c5659abe141de0b8fa6bd534051 11:18:48 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 11:18:48 INFO - mozversion platform_version: 48.0a1 11:18:48 INFO - using testdate: 1461089928 11:18:48 INFO - actual date: 1461089928 11:18:48 INFO - starting webserver on 'localhost:49276' 11:18:48 INFO - SUITE-START | Running 2 tests 11:18:48 INFO - TEST-START | dromaeo_css 11:18:48 INFO - operating with platform_type : mac_ 11:18:48 INFO - Initialising browser for dromaeo_css test... 11:18:48 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/tmpngUrxy/profile http://localhost:49276/getInfo.html) 11:18:50 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 11:18:50 INFO - PROCESS | 1669 | colorDepth:24 11:18:50 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 11:18:50 INFO - PROCESS | 1669 | __metrics 11:18:51 INFO - PROCESS | 1669 | 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" 11:18:51 ERROR - PROCESS | 1669 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 11:18:51 INFO - TEST-INFO | 1669: exit 0 11:18:51 INFO - Browser initialized. 11:18:51 INFO - Running cycle 1/1 for dromaeo_css test... 11:18:51 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/tmpngUrxy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:19:03 INFO - PROCESS | 1671 | RSS: Main: 237301760 11:19:03 INFO - PROCESS | 1671 | 11:19:55 INFO - PROCESS | 1671 | 1461089995171 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] 11:22:05 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49276/tests/dromaeo/cssquery-ext.html) 11:22:06 INFO - PROCESS | 1671 | RSS: Main: 364584960 11:22:06 INFO - PROCESS | 1671 | 11:25:09 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-ext.html (next: http://localhost:49276/tests/dromaeo/cssquery-jquery.html) 11:25:10 INFO - PROCESS | 1671 | RSS: Main: 368852992 11:25:10 INFO - PROCESS | 1671 | 11:28:12 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49276/tests/dromaeo/cssquery-mootools.html) 11:28:12 INFO - PROCESS | 1671 | RSS: Main: 377311232 11:28:12 INFO - PROCESS | 1671 | 11:31:14 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49276/tests/dromaeo/cssquery-prototype.html) 11:31:15 INFO - PROCESS | 1671 | RSS: Main: 372621312 11:31:15 INFO - PROCESS | 1671 | 11:34:18 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49276/tests/dromaeo/cssquery-yui.html) 11:34:19 INFO - PROCESS | 1671 | RSS: Main: 341397504 11:34:19 INFO - PROCESS | 1671 | 11:37:22 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-yui.html (next: http://localhost:49276/tests/dromaeo/cssquery-dojo.html) 11:37:22 INFO - PROCESS | 1671 | RSS: Main: 383598592 11:37:22 INFO - PROCESS | 1671 | 11:37:22 INFO - PROCESS | 1671 | __start_tp_report 11:37:22 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 11:37:22 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 11:37:22 INFO - PROCESS | 1671 | |i|pagename|runs| 11:37:22 INFO - PROCESS | 1671 | |0;dojo.html;3163.5571020057196;3459.827008649567;3483.1814523586936;3488.6860182583564;3534.8232588370574;5387.057264978633;5510.8071217507395;5633.21135041094;5652.406497317784;5661.886762264758;5633.464820842024;5690.5447564194865;5761.279840019997;5785.190073389729;5789.71051447427;5044.924326135111;5104.387473503185;5130.871728206787;5130.974345128269;5201.219817027457;5177.948220517785;5214.7653355598895;5232.555232805197;5238.607104467161;5279.894402111955;5036.370453693288;5086.440491545927;5101.15830887903;5125.308083408767;5153.948460515403;3775.8300876460626;5110.897782044375;5117.130087885032;5126.026055049529;5145;5056.367954005749;5095.898082038376;5125.38495380553;5130.846074617767;5137.948620513785;5461.863453413656;5544.16837474378;5593.300837395325;5660.066089095294;5669.546436285129;6063.363346848561;6138.8772224555705;6158.230221222348;6160.1375807387;6176.81469555914;6129.632222066691;6159.630422174684;6204.069389591552;6208.317085120633;6211.254649442096;5718.42815718429;5721.799737009184;5825.300963884319;5826.65040097587;5831.0378787499585;6235.095911092866;6244.12582238487;6258.217722784652;6269.435750782361;6308.684565771693;6148.754049838046;6164.7842325518395;6169.259688837368;6176.722047507873;6178.505719542426;6098.311206366262;6132.4778862607845;6254.249490061222;6256.0303153011355;6262.655553944577;6175.166352542349;6228.937710622927;6230;6240.781572644935;6248.343923888017;6137.447629713347;6215.098810672427;6247.812565623039;6251.562390632612;6271.466925311308;4915.16442204826;4921.261810728348;4925.9014819704125;4938.580220681282;4986.6758660687665;4398.758068306274;4411.691181617321;4412.029353542215;4420.049689316748;4468.955310446854;5598.888022239533;5714.171445140431;5718.857028574319;5719.8570035749435;5747.655140691572;6169.105479705508;6228.750849965951;6253.187085678833;6260.186175797117;6285.560010799291;5677.37548869624;5678.4605462480995;5679.148127780783;5708.286464191976;5717.714114294268;5306.893862122736;5379.085555455582;5379.112446446293;5390.164524498709;5394.190871369326;6203.627782333075;6242.656653883989;6256.280527739277;6258.906116408349;6287;6199.16311297969;6241.063840423973;6262.123302737529;6272.498200143887;6279.152314437492;6170.382961703974;6347.301796802441;6363;6366.29970703213;6384.553081284265;5162.561182299547;6100.944524685144;6166.013437849922;6208.72060757258;6217.347178546188;6246.5002799775;6253.687315634291;6259.217597800275;6272.372762723691;6272.40412160844;5989.371116032754;6113.938860611337;6128.295246046761;6138.846528836636;6144.938550614615;6058.151858739868;6112.837854638479;6137.647534801459;6138.480361557057;6146.7091910699255;4586.88532786683;5898.882022359615;5900.911486327795;5947.881042379215;5949.881002379841;5987.700614969322;6029.668368239789;6094.299155597163;6125.295591006913;6151.938480615316;6033.276006879027;6034.939650603438;6047.274327080779;6057.242844644419;6098.207233059674 11:37:22 INFO - PROCESS | 1671 | |1;ext.html;532.6085327284447;534.2200387434345;540.8134193703171;554.2157846646993;560.1457776890239;29544;30062.098137055895;38846.41730374047;38892.61107388925;39055;36714.81642591783;42217.57782422175;42630;42697.7865110674;42705.57294427063;8476.49141051539;9445;9680.951595242013;9703;9703.320767546275;11488.71278218047;11886.28682279066;11888.524459021672;11894.226875253082;11894.988925941305;11568.074554035657;11657.475413606408;11662.358570278631;11664.35846028468;11674.47464864079;11522.366269855154;11618.360990145538;11631.88368116325;11634;11656;11388.259763115371;11504.02215811657;11512.251703639236;11513.366764827932;11536.30782153065;40344.596554034375;40682.37270509176;40751.592484075074;40775;40816.79591602023;3224;3267.313864088544;3401.4727717203627;3567.8037707926055;3818.369968955119;29070.709292907013;29304.56043159354;29312.120636380943;29419.85290073536;29759.25601859949;2641.7886569074426;2695.6495655564845;2729.4541091781725;2774.833509989407;2790.4279622677304;480639;480925;481472;482057;483199;62977.68511157415;63415.68292158602;63421.36578634247;63529.36470635327;63599;60272.69863650654;68113.63772724518;68123.65938170278;68182.9772553412;68245.65877170583;16772.916135419488;17308.740368894476;17349.826501735075;17366.21852016662;17368;13027.022973277042;13050.804237936438;13091.149075310075;13098.476060957455;13101.41043653038;17860.19629116667;18340.816591834177;18349;18406.81593184051;18437.446876593724;6317.8312012277875;6414.61512309263;6745.898811517831;6940.930590694028;6941.409980151744;977.4868194197961;983.680303901235;994.5226291380178;1004.7237009822358;1112.071420364005;28514.28714282118;29487;29789;30248.395032099234;30305.090847274616;1010.166612544639;1096.4024606589428;1154.5670373609896;1167.96496105117;1194.6714653470192;15325.31036103378;15366.231688415535;15551.066935983878;15670.059796412252;15693.372265109496;614600;702246;711401;711906;712389;53784.46215537873;53858.461415386126;54473.455265446835;54489;54611.180832287544;1279.5329704657734;1337.8193943817655;1374.752544541992;1498.2583621107403;1522.3073501556764;29983.550246746752;30445.568028110523;30700.84649576738;30942.84528577433;30972.0708378749;53246;53383.4661653394;53910.730446347516;53919.46080539301;53988.19017714816;400312;403867;404346;404807.9759601183;405360;23864.64203036921;26589.468210636067;26685.93256269805;27307.453850922473;27732.8906843724;42528;42722;43061.56938430575;43507;43994.560054399044;34270.282682161065;35842.4623630651;35843.46234806428;36441.81779091088;36449.271014579026;35450.93647190589;36358;36379;37216.44175337426;37281.81359093187;36739.26521469502;36771.81614091912;37119.814400927826;37868;38218.808905955295;30157.642906068842;31832.045038648877;31834;31855.20361990876;32002.35995280128;21178.152873885487;23291;24486.75513244845;24562.877185614674;25170.37074073163 11:37:22 INFO - PROCESS | 1671 | |2;jquery.html;392.08057106086216;392.64857952132843;393.85427391865016;397.6639739420189;404.6075306952257;13606.863931360684;19787.802121978777;20485.07817148228;20499.897500512514;20538.383848484533;12904.483820647187;15999.120048397333;19725.802741972577;20210.59578808423;20356.796432035677;5765.6540607563475;6096.7561297548045;6134.263888333407;6138.877222455548;6153.476954458876;5952.107183922403;6021.277446706401;6048.939510604903;6104.725287362057;6124.234470691164;5824.912626310609;6015.548833837458;6042.516598672096;6049.243844519435;6065.818025459222;5619.634723742964;5991.460768530831;6042.516598672096;6056.364081771395;6060.727267272983;5851.707414629252;5864.44287792659;5870.0314448116005;5875.9118613220835;5882.29412470502;13964.860351396457;14514.782278265835;21157.788422115736;25910.87044564784;25924.870375648003;2084.4059443058654;2106.3680895731372;2125.1499400239873;2137.551114266006;2297.7357603875603;4204.579542045802;9741.366811157252;10132.594696212143;10138.645147419878;10616.853781143474;1692.505046688079;1736.366226327394;1777.5200695812186;1784.0633667324628;1835.4218421197281;120177.7982220184;124340;125238.12142817774;125608.37195813963;127805.36097319453;11391.373474458818;11625;11633.41832908368;11688;11719.707007324885;15320.23398830054;15376.538703838904;15378.923105384401;15408.075515469107;15413.691726165638;7490.2135275796345;7621.847563048708;8090.231428014329;8550.871736923951;8702.608382622797;8353.039400469024;8372.246497815226;8384.287335576422;8468.364872634576;8475.74572762818;4528.841490547815;4543.704659197141;4553.430821147356;4564.360989461478;4615.169269531517;1781.385422029398;1824.5621050947677;1877.6714075036814;1945.2413558712115;1964.7347608073012;761.8362052158701;920.5673333533229;961.7547525381123;1013.4476710192963;1027.9331843430152;17049.40327088571;17420;19596.020198989994;19849.40451786449;20221.797782021993;927.1470247372433;973.7468258252763;985.0789511806483;1032.6420756704908;1033.3644808442905;10123.076615443042;10678.359298442118;10699.73163701415;10750.516226769814;10829.133669306437;510301;525626;582198;602942;604403;139881.3005934964;143641;143926;144442.27778861037;148121.5187848129;882.1531329923217;1026.7227848481018;1043.9895601043893;1044.2794471814423;1046.5029111172132;16015.839841601435;16559.668806624042;17803;18164;18522.536936576693;48778.75610621924;50158.7492062552;50954;50993;52806.471935280155;65987.67006164939;71093.64453177701;73206.26793732207;74173;75557.22861728113;7127.394171495273;7882.487638303492;8017.118117007241;8116;8401.285890699115;8542.932133483315;11828.053755699351;12386;12670.619881403572;12838.871611284141;10637.095846852795;10924.2899211551;10954.342739435659;11035.448227588427;11168.441577921234;10634.787304253718;10734.677959661223;10815.297005694605;11152.665420037412;11154.442277885912;10823.729406764578;11027.448627568428;11245.381504017358;11294.60468883585;11356.602518911799;7370.373518251009;7446.813829654302;7592.506487078323;7614.314711675976;7717.729879454192;10258.897411025795;10899.29154604948;11001.394923279297;11340.922612351813;11343.97904188627 11:37:22 INFO - PROCESS | 1671 | |3;mootools.html;1388.2919710947415;1594.09933387636;1618.2960412220682;1853.2123847364876;1968.1438574220206;7951.12537620862;8658.653653853846;8885.866711999326;8889.977652569956;8899;8511.74464766058;8707.303415726743;8715.782105447366;8715.956420217906;8717.694880679177;2394.928152155434;2428.64784606232;2429.4776623026046;2429.987850060756;2434.525267572824;2381.452265978826;2419.830611857171;2421.588329983907;2424.0425032112307;2431.258466167827;2115.7038014677955;2307.746147923727;2405.8797060147012;2408.7952524035477;2419.733829278778;2376.9762302376926;2396.25716028031;2406;2408.2534414331526;2414.5895197816412;2359.3275916363755;2374.3233178544033;2383.249276477904;2387.85672859629;2391.545606334791;8511.702090426805;8776.868346974801;8862.42394244372;8867.512286824222;8887.422317549403;4227.72519786216;4866.829660961849;5178.093833579109;5341.973290133564;5387.24578559002;3126.640436349803;3350.1289664687356;3417.470292104703;3427.1260828488826;3529.1353618363664;998.8862418403501;1005.4771518810178;1020.2552136940001;1023.5598692562195;1026.3944272879037;11154.274972126786;11477.885221147848;11478.598249061242;11483.138764592606;11513.88486115145;3686.5391826021746;4409.272470042407;4533.77331133442;4559.726416415026;4574.839880604163;9026.413283136575;9028.593713282919;9042.819143617091;9043.728688139367;9045.954770226106;4145.854895078658;4804.015176888729;5068.290439338495;5197.870053248624;5237.423883372826;4347;4517.073999830027;4717.669763116617;4869.63477739171;4899.4365647950945;3250.431174544445;3671.7148997851073;3699.112213068844;3763.8682646107804;3815.465834783133;1159.3333833045863;1163.5694792926672;1171.543098191706;1174.172208592957;1180.6399048290355;648.8215740671353;766.2414758130104;903.7424334064891;909.1999040844015;930.4789317981952;5054.267131265946;5095.745212739348;5559.221708960748;5648.63283886546;5719.685417302088;555.0924238869477;560.7616762875844;572.7136431784108;574.3423779772221;668.0346898731286;3124.718775310233;3692.409214525663;3814.7520411173186;3848.8652897148463;3912.4522566840665;10136.797264054678;10612.57549698019;10678.893211067945;10783.6225732099;10836.945815270874;10562.630307939186;10565.9471702641;10573.841392379121;10708.250422470352;10750.73123171927;7719.845603087907;8090.757277281691;8150.796230094295;8156.592170391456;8208.015038195335;5749.338826035059;5814.389489103583;5819.359870414166;5887.0580707086665;6051.364606716232;9574.90425095768;9780.217582593234;9897.010298970334;9903.851442228228;9908.702738917844;9331.346805723535;9991.050850169222;10081.99180081986;10083.890772014925;10090.99090090985;7834.764957051298;8176.8364632705825;8196;8206.179382061984;8208.794780130545;6411.807645770635;7900.644470998704;7972.043354797462;7982.401319901147;7982.720604778805;7300.96349518249;7309.488335816442;7319.158296795937;7423.517471364344;7472.178060413242;7127.928720712934;7305.671244794104;7318.012068370702;7379.188289288281;7453.552786832807;6688.3646053624825;6754.155730533684;6777.356151165632;6779.627120508222;6792.0830687856505;4604.286335617984;4750.596199323097;4783.545563171493;4826.855194344175;4968.453470118357;4079.5920407958965;4801.159797035574;4819.132556139928;4833.250846118857;5041.899162016666 11:37:22 INFO - PROCESS | 1671 | |4;prototype.html;314.5062252263946;319.0842282648798;324.1183979575554;330.06755914541424;335.1403649638678;5289.735513224337;5884.557159344688;5897.823065308037;5898.262717160355;5935.9703201483935;5287.709175995319;5473.28847249858;5730.656160630365;5828.562857785672;5858.589898707092;3282.7853694133205;3777.9622203777885;3992.7005474589523;4004.8786339824896;4430.9556904430865;3575.4100573405485;4343.196508645895;4376.168527979689;4400.559944005606;4410.095930334273;4370.1915145697985;4389.736615803062;4391.758453285068;4409.9118017639785;4410.889727756799;3643.599204087548;4112.280350938574;4133.689973252018;4165.604267594574;4335.761533115678;3608.9278214435826;3788.71584631151;3790.550598954965;3805.315043292206;3827.162961778362;5124.692518448867;5414.938795903073;5600.747966341524;5658.151277308396;5673.886522269573;4415.690901636886;4809.75951202442;5057.367829021372;5093.770780314858;5129.6409251352425;5061.75986883216;5073.898522029539;6135.171751813493;6137.079438084278;6141.570090093695;4121.216968775923;4256.7445953242595;4276.498248524616;4281.079567892918;4292.248856450109;6134.079888016789;6414.61512309263;6507.316731743146;6524.217093948757;6537.673116344164;4224.619784219435;4287.828486860491;4311.956880431218;4359.215341238608;4389.978050109729;3911.198204368098;4430.654855584014;4442;4445.73325600465;4449.7330160190495;4036;4328.5022222444195;5124.180131179068;5130.4356520782685;5175.948240517547;5924.289085309704;6034.275886893601;6251.343608921089;6256.593321434092;6327.841803954846;1464.3526033212636;1513.2585033333587;1535.6007438066183;1537.2621141851973;1539.5612250508775;868.2663149638545;956.899565499667;978.6770365779275;1011.5195282240877;1027.2039169643585;5329.520343169056;5492.752826122834;5750.712464376765;5892.351841297454;5917.721677154241;6500.992346186349;6511.958086706199;6527.379898909596;6533.379328963741;6550.737970481223;5019.272205530177;5229.581633469314;5906.202662640574;5941.346451890288;5949.8215053548465;3044.786864919479;3063.6979283804294;3190.7766456348295;3261.62491313502;3305.8677652893616;5502.724863756796;6518.380753828474;6721;6760.830979225559;6793.5244532882225;5453.64551304164;6024.457798798129;6606.7026983785845;6618.338166183439;6664.433523150587;5054.047033906719;5299.8410047698635;6688;6697;6730.831729206809;4829.468758436639;6364.713587888464;6387.5209359297305;6437.8175568667675;6471.288158302677;6332;6380.712867921047;6393.104965304768;6400.743970241138;6425.1128067518475;5839.532837373085;5859.765609375577;6399.232092148972;6700.363465470968;6717.529772915849;3863.787491687984;4182.749035057785;4190.1200747843295;4221.577842215754;4242;5358.892822143614;6236.376362363727;6482.35176482348;6491.740330386731;6501.83882315328;6252.874942501216;6281.748730050746;6284.968575157278;6308.116863639002;6311.495080393649;5121.871953201051;5909.911351329648;5912.201852749824;6215.689215539295;6289.685515724287;5918.858698090511;6133.785317513865;6133.877322453615;6138.600990935575;6168.537359697951;5533.529649979636;6127.835711214855;6128.662923539248;6182;6233.594816336924;5099.5665368442615;5228.398734145622;5376.435474275301;6099.2985806632805;6228.56400051992 11:37:22 INFO - PROCESS | 1671 | |5;yui.html;714.932081452262;725.4087918346547;730.2843213650625;738.8633102875968;768.8577613141571;11886.524539018428;15172;15184;15199.468018619351;15199.544013679593;13030.218186908793;15497.845021549781;16054.03675779454;16075.83924160758;16083.597910052225;726.8400951790597;917.1882883647986;927.6753136402273;939.506758951549;941.0072373645801;740.8518296340753;828.353883970504;876.7325965580527;937.5827756648295;937.8874535055803;968.5351031504882;975.6487664440796;978.021978021978;980.7302991677275;980.8675828763097;748.9775306740806;939.2486011191088;960.6445615122415;970.4177493503912;970.8834939807233;721.8267615772229;725.6661935509671;726.3862036579083;727.7271023366237;727.9781606551812;12626.30555319457;15094.094354338775;15143;15160.620984475367;15251.542453726295;10950.507227174796;11288.096952243883;12173.817392739116;12399;12663.683407914783;9808.362456440309;10199.54102065409;10209.948950255275;10475.47622618866;10647.84028239577;3400.353932752769;3444.1045328214595;3461.549998500204;3508.6842184203288;3715.034091136297;90248.09751902462;91617.54191229068;93317.99999999932;93728.53135734345;93738.53130734371;8009.639566219534;9264.861027084598;9390.389624674373;9407.576659050359;9641.710748677551;21474.463138421663;21513.13947442116;21529.784702152778;21547.67678484824;21574.676379854314;7382.261773822682;7472.887906681404;7497.212792656802;7507.512011719221;7611.695532178651;4072.1477263094116;4146.981365964878;4154.044569748914;4167.354060120686;4181.540030596632;3272.378248132847;3369.22507823202;3369.713574346159;3369.730421566269;3372.2581032172884;611.5718996702237;622.9781957631461;623.9875202495924;628.6353914729445;632.0645444741716;495.8834673851642;544.1130956540892;573.9697243448021;608.3369127650882;610.9602875813058;7754;7831.177726338652;7969.561674107979;8098.959505202554;8144.837103257902;565.1212364772817;574.379669956446;578.9507891829157;621.7171187109855;629.5467263570222;3662.4506324051604;3744.8127593620757;3791.9431208531896;3894.9610503894596;3930.3907894275862;92723;95573.04426955641;97802;98078.50960745293;98183;78090.6095469519;78100;78428.02007718924;78894;79474.2841145414;60427;61779.38220617826;70247.2975270251;72106;72548.63725681338;7651.579163146081;7900.644470998934;7970.322522585413;7988.48506657069;8341.624626891684;42999.57000429956;43389.566104338555;43490;45069.54930450654;45269;47780.28329575129;63655.72688546296;63812;63901.0414843787;64102.3589764115;10965.396903170402;11020.724481887697;11160.609378672032;11180.55277788912;11223;11434.885651143715;11715.882841171479;11791.528338866348;11878.406079695807;12195.085368597212;11235.943820280847;11326.150538709466;11560.421978900853;11616.186866919235;11676.007539359249;11274.379909105079;11280.717982050515;11287.163256660235;11488.540458381905;11574.363410012531;11067.280626759235;11156.386398748147;11211.831822522505;11506.827397588875;11940.641780746244;9426.387284826464;9608.27937904646;9641.951790241004;9769.120779129913;9853.802923941625;9578;9756.07317304855;9858.162056225014;9862.802743944938;9932.950335248277 11:37:22 INFO - PROCESS | 1671 | __end_tp_report 11:37:22 INFO - PROCESS | 1671 | __start_cc_report 11:37:22 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,1219 11:37:22 INFO - PROCESS | 1671 | __end_cc_report 11:37:22 INFO - PROCESS | 1671 | __startTimestamp1461091042518__endTimestamp 11:37:22 INFO - PROCESS | 1671 | 11:37:22 INFO - PROCESS | 1671 | ------- Summary: start ------- 11:37:22 INFO - PROCESS | 1671 | Number of tests: 6 11:37:22 INFO - PROCESS | 1671 | 11:37:22 INFO - PROCESS | 1671 | [#0] dojo.html Cycles:165 Average:5707.49 Median:5989.37 stddev:662.16 (11.1%) stddev-sans-first:633.40 11:37:22 INFO - PROCESS | 1671 | Values: 3163.6 3459.8 3483.2 3488.7 3534.8 5387.1 5510.8 5633.2 5652.4 5661.9 5633.5 5690.5 5761.3 5785.2 5789.7 5044.9 5104.4 5130.9 5131.0 5201.2 5177.9 5214.8 5232.6 5238.6 5279.9 5036.4 5086.4 5101.2 5125.3 5153.9 3775.8 5110.9 5117.1 5126.0 5145.0 5056.4 5095.9 5125.4 5130.8 5137.9 5461.9 5544.2 5593.3 5660.1 5669.5 6063.4 6138.9 6158.2 6160.1 6176.8 6129.6 6159.6 6204.1 6208.3 6211.3 5718.4 5721.8 5825.3 5826.7 5831.0 6235.1 6244.1 6258.2 6269.4 6308.7 6148.8 6164.8 6169.3 6176.7 6178.5 6098.3 6132.5 6254.2 6256.0 6262.7 6175.2 6228.9 6230.0 6240.8 6248.3 6137.4 6215.1 6247.8 6251.6 6271.5 4915.2 4921.3 4925.9 4938.6 4986.7 4398.8 4411.7 4412.0 4420.0 4469.0 5598.9 5714.2 5718.9 5719.9 5747.7 6169.1 6228.8 6253.2 6260.2 6285.6 5677.4 5678.5 5679.1 5708.3 5717.7 5306.9 5379.1 5379.1 5390.2 5394.2 6203.6 6242.7 6256.3 6258.9 6287.0 6199.2 6241.1 6262.1 6272.5 6279.2 6170.4 6347.3 6363.0 6366.3 6384.6 5162.6 6100.9 6166.0 6208.7 6217.3 6246.5 6253.7 6259.2 6272.4 6272.4 5989.4 6113.9 6128.3 6138.8 6144.9 6058.2 6112.8 6137.6 6138.5 6146.7 4586.9 5898.9 5900.9 5947.9 5949.9 5987.7 6029.7 6094.3 6125.3 6151.9 6033.3 6034.9 6047.3 6057.2 6098.2 11:37:22 INFO - PROCESS | 1671 | 11:37:22 INFO - PROCESS | 1671 | [#1] ext.html Cycles:180 Average:66454.01 Median:3334.39 stddev:144330.68 (4328.5%) stddev-sans-first:144650.70 11:37:22 INFO - PROCESS | 1671 | Values: 532.6 534.2 540.8 554.2 560.1 29544.0 30062.1 38846.4 38892.6 39055.0 36714.8 42217.6 42630.0 42697.8 42705.6 8476.5 9445.0 9681.0 9703.0 9703.3 11488.7 11886.3 11888.5 11894.2 11895.0 11568.1 11657.5 11662.4 11664.4 11674.5 11522.4 11618.4 11631.9 11634.0 11656.0 11388.3 11504.0 11512.3 11513.4 11536.3 40344.6 40682.4 40751.6 40775.0 40816.8 3224.0 3267.3 3401.5 3567.8 3818.4 29070.7 29304.6 29312.1 29419.9 29759.3 2641.8 2695.6 2729.5 2774.8 2790.4 480639.0 480925.0 481472.0 482057.0 483199.0 62977.7 63415.7 63421.4 63529.4 63599.0 60272.7 68113.6 68123.7 68183.0 68245.7 16772.9 17308.7 17349.8 17366.2 17368.0 13027.0 13050.8 13091.1 13098.5 13101.4 17860.2 18340.8 18349.0 18406.8 18437.4 6317.8 6414.6 6745.9 6940.9 6941.4 977.5 983.7 994.5 1004.7 1112.1 28514.3 29487.0 29789.0 30248.4 30305.1 1010.2 1096.4 1154.6 1168.0 1194.7 15325.3 15366.2 15551.1 15670.1 15693.4 614600.0 702246.0 711401.0 711906.0 712389.0 53784.5 53858.5 54473.5 54489.0 54611.2 1279.5 1337.8 1374.8 1498.3 1522.3 29983.6 30445.6 30700.8 30942.8 30972.1 53246.0 53383.5 53910.7 53919.5 53988.2 400312.0 403867.0 404346.0 404808.0 405360.0 23864.6 26589.5 26685.9 27307.5 27732.9 42528.0 42722.0 43061.6 43507.0 43994.6 34270.3 35842.5 35843.5 36441.8 36449.3 35450.9 36358.0 36379.0 37216.4 37281.8 36739.3 36771.8 37119.8 37868.0 38218.8 30157.6 31832.0 31834.0 31855.2 32002.4 21178.2 23291.0 24486.8 24562.9 25170.4 11:37:22 INFO - PROCESS | 1671 | 11:37:22 INFO - PROCESS | 1671 | [#2] jquery.html Cycles:180 Average:34099.87 Median:20519.14 stddev:95506.37 (465.5%) stddev-sans-first:95740.76 11:37:22 INFO - PROCESS | 1671 | Values: 392.1 392.6 393.9 397.7 404.6 13606.9 19787.8 20485.1 20499.9 20538.4 12904.5 15999.1 19725.8 20210.6 20356.8 5765.7 6096.8 6134.3 6138.9 6153.5 5952.1 6021.3 6048.9 6104.7 6124.2 5824.9 6015.5 6042.5 6049.2 6065.8 5619.6 5991.5 6042.5 6056.4 6060.7 5851.7 5864.4 5870.0 5875.9 5882.3 13964.9 14514.8 21157.8 25910.9 25924.9 2084.4 2106.4 2125.1 2137.6 2297.7 4204.6 9741.4 10132.6 10138.6 10616.9 1692.5 1736.4 1777.5 1784.1 1835.4 120177.8 124340.0 125238.1 125608.4 127805.4 11391.4 11625.0 11633.4 11688.0 11719.7 15320.2 15376.5 15378.9 15408.1 15413.7 7490.2 7621.8 8090.2 8550.9 8702.6 8353.0 8372.2 8384.3 8468.4 8475.7 4528.8 4543.7 4553.4 4564.4 4615.2 1781.4 1824.6 1877.7 1945.2 1964.7 761.8 920.6 961.8 1013.4 1027.9 17049.4 17420.0 19596.0 19849.4 20221.8 927.1 973.7 985.1 1032.6 1033.4 10123.1 10678.4 10699.7 10750.5 10829.1 510301.0 525626.0 582198.0 602942.0 604403.0 139881.3 143641.0 143926.0 144442.3 148121.5 882.2 1026.7 1044.0 1044.3 1046.5 16015.8 16559.7 17803.0 18164.0 18522.5 48778.8 50158.7 50954.0 50993.0 52806.5 65987.7 71093.6 73206.3 74173.0 75557.2 7127.4 7882.5 8017.1 8116.0 8401.3 8542.9 11828.1 12386.0 12670.6 12838.9 10637.1 10924.3 10954.3 11035.4 11168.4 10634.8 10734.7 10815.3 11152.7 11154.4 10823.7 11027.4 11245.4 11294.6 11356.6 7370.4 7446.8 7592.5 7614.3 7717.7 10258.9 10899.3 11001.4 11340.9 11344.0 11:37:22 INFO - PROCESS | 1671 | 11:37:22 INFO - PROCESS | 1671 | [#3] mootools.html Cycles:180 Average:5567.88 Median:4850.04 stddev:3170.58 (65.4%) stddev-sans-first:3163.91 11:37:22 INFO - PROCESS | 1671 | Values: 1388.3 1594.1 1618.3 1853.2 1968.1 7951.1 8658.7 8885.9 8890.0 8899.0 8511.7 8707.3 8715.8 8716.0 8717.7 2394.9 2428.6 2429.5 2430.0 2434.5 2381.5 2419.8 2421.6 2424.0 2431.3 2115.7 2307.7 2405.9 2408.8 2419.7 2377.0 2396.3 2406.0 2408.3 2414.6 2359.3 2374.3 2383.2 2387.9 2391.5 8511.7 8776.9 8862.4 8867.5 8887.4 4227.7 4866.8 5178.1 5342.0 5387.2 3126.6 3350.1 3417.5 3427.1 3529.1 998.9 1005.5 1020.3 1023.6 1026.4 11154.3 11477.9 11478.6 11483.1 11513.9 3686.5 4409.3 4533.8 4559.7 4574.8 9026.4 9028.6 9042.8 9043.7 9046.0 4145.9 4804.0 5068.3 5197.9 5237.4 4347.0 4517.1 4717.7 4869.6 4899.4 3250.4 3671.7 3699.1 3763.9 3815.5 1159.3 1163.6 1171.5 1174.2 1180.6 648.8 766.2 903.7 909.2 930.5 5054.3 5095.7 5559.2 5648.6 5719.7 555.1 560.8 572.7 574.3 668.0 3124.7 3692.4 3814.8 3848.9 3912.5 10136.8 10612.6 10678.9 10783.6 10836.9 10562.6 10565.9 10573.8 10708.3 10750.7 7719.8 8090.8 8150.8 8156.6 8208.0 5749.3 5814.4 5819.4 5887.1 6051.4 9574.9 9780.2 9897.0 9903.9 9908.7 9331.3 9991.1 10082.0 10083.9 10091.0 7834.8 8176.8 8196.0 8206.2 8208.8 6411.8 7900.6 7972.0 7982.4 7982.7 7301.0 7309.5 7319.2 7423.5 7472.2 7127.9 7305.7 7318.0 7379.2 7453.6 6688.4 6754.2 6777.4 6779.6 6792.1 4604.3 4750.6 4783.5 4826.9 4968.5 4079.6 4801.2 4819.1 4833.3 5041.9 11:37:22 INFO - PROCESS | 1671 | 11:37:22 INFO - PROCESS | 1671 | [#4] prototype.html Cycles:180 Average:4994.56 Median:5483.02 stddev:1573.96 (28.7%) stddev-sans-first:1538.67 11:37:22 INFO - PROCESS | 1671 | Values: 314.5 319.1 324.1 330.1 335.1 5289.7 5884.6 5897.8 5898.3 5936.0 5287.7 5473.3 5730.7 5828.6 5858.6 3282.8 3778.0 3992.7 4004.9 4431.0 3575.4 4343.2 4376.2 4400.6 4410.1 4370.2 4389.7 4391.8 4409.9 4410.9 3643.6 4112.3 4133.7 4165.6 4335.8 3608.9 3788.7 3790.6 3805.3 3827.2 5124.7 5414.9 5600.7 5658.2 5673.9 4415.7 4809.8 5057.4 5093.8 5129.6 5061.8 5073.9 6135.2 6137.1 6141.6 4121.2 4256.7 4276.5 4281.1 4292.2 6134.1 6414.6 6507.3 6524.2 6537.7 4224.6 4287.8 4312.0 4359.2 4390.0 3911.2 4430.7 4442.0 4445.7 4449.7 4036.0 4328.5 5124.2 5130.4 5175.9 5924.3 6034.3 6251.3 6256.6 6327.8 1464.4 1513.3 1535.6 1537.3 1539.6 868.3 956.9 978.7 1011.5 1027.2 5329.5 5492.8 5750.7 5892.4 5917.7 6501.0 6512.0 6527.4 6533.4 6550.7 5019.3 5229.6 5906.2 5941.3 5949.8 3044.8 3063.7 3190.8 3261.6 3305.9 5502.7 6518.4 6721.0 6760.8 6793.5 5453.6 6024.5 6606.7 6618.3 6664.4 5054.0 5299.8 6688.0 6697.0 6730.8 4829.5 6364.7 6387.5 6437.8 6471.3 6332.0 6380.7 6393.1 6400.7 6425.1 5839.5 5859.8 6399.2 6700.4 6717.5 3863.8 4182.7 4190.1 4221.6 4242.0 5358.9 6236.4 6482.4 6491.7 6501.8 6252.9 6281.7 6285.0 6308.1 6311.5 5121.9 5909.9 5912.2 6215.7 6289.7 5918.9 6133.8 6133.9 6138.6 6168.5 5533.5 6127.8 6128.7 6182.0 6233.6 5099.6 5228.4 5376.4 6099.3 6228.6 11:37:22 INFO - PROCESS | 1671 | 11:37:22 INFO - PROCESS | 1671 | [#5] yui.html Cycles:180 Average:18300.50 Median:31200.55 stddev:26307.77 (84.3%) stddev-sans-first:26348.44 11:37:22 INFO - PROCESS | 1671 | Values: 714.9 725.4 730.3 738.9 768.9 11886.5 15172.0 15184.0 15199.5 15199.5 13030.2 15497.8 16054.0 16075.8 16083.6 726.8 917.2 927.7 939.5 941.0 740.9 828.4 876.7 937.6 937.9 968.5 975.6 978.0 980.7 980.9 749.0 939.2 960.6 970.4 970.9 721.8 725.7 726.4 727.7 728.0 12626.3 15094.1 15143.0 15160.6 15251.5 10950.5 11288.1 12173.8 12399.0 12663.7 9808.4 10199.5 10209.9 10475.5 10647.8 3400.4 3444.1 3461.5 3508.7 3715.0 90248.1 91617.5 93318.0 93728.5 93738.5 8009.6 9264.9 9390.4 9407.6 9641.7 21474.5 21513.1 21529.8 21547.7 21574.7 7382.3 7472.9 7497.2 7507.5 7611.7 4072.1 4147.0 4154.0 4167.4 4181.5 3272.4 3369.2 3369.7 3369.7 3372.3 611.6 623.0 624.0 628.6 632.1 495.9 544.1 574.0 608.3 611.0 7754.0 7831.2 7969.6 8099.0 8144.8 565.1 574.4 579.0 621.7 629.5 3662.5 3744.8 3791.9 3895.0 3930.4 92723.0 95573.0 97802.0 98078.5 98183.0 78090.6 78100.0 78428.0 78894.0 79474.3 60427.0 61779.4 70247.3 72106.0 72548.6 7651.6 7900.6 7970.3 7988.5 8341.6 42999.6 43389.6 43490.0 45069.5 45269.0 47780.3 63655.7 63812.0 63901.0 64102.4 10965.4 11020.7 11160.6 11180.6 11223.0 11434.9 11715.9 11791.5 11878.4 12195.1 11235.9 11326.2 11560.4 11616.2 11676.0 11274.4 11280.7 11287.2 11488.5 11574.4 11067.3 11156.4 11211.8 11506.8 11940.6 9426.4 9608.3 9642.0 9769.1 9853.8 9578.0 9756.1 9858.2 9862.8 9933.0 11:37:22 INFO - PROCESS | 1671 | -------- Summary: end -------- 11:37:22 INFO - PROCESS | 1671 | 11:37:23 INFO - TEST-INFO | 1671: exit 0 11:37:23 INFO - TEST-OK | dromaeo_css | took 1114322ms 11:37:23 INFO - TEST-START | kraken 11:37:23 INFO - operating with platform_type : mac_ 11:37:23 INFO - Initialising browser for kraken test... 11:37:23 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/tmpOq2D_l/profile http://localhost:49276/getInfo.html) 11:37:25 INFO - PROCESS | 1679 | __metrics Screen width/height:1600/1200 11:37:25 INFO - PROCESS | 1679 | colorDepth:24 11:37:25 INFO - PROCESS | 1679 | Browser inner width/height: 1024/689 11:37:25 INFO - PROCESS | 1679 | __metrics 11:37:25 INFO - PROCESS | 1679 | 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" 11:37:25 ERROR - PROCESS | 1679 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 11:37:26 INFO - TEST-INFO | 1679: exit 0 11:37:26 INFO - Browser initialized. 11:37:26 INFO - Running cycle 1/1 for kraken test... 11:37:26 INFO - TEST-INFO | started process 1681 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/tmpOq2D_l/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:37:38 INFO - PROCESS | 1681 | RSS: Main: 237355008 11:37:38 INFO - PROCESS | 1681 | 11:37:40 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection) 11:37:41 INFO - PROCESS | 1681 | RSS: Main: 285794304 11:37:41 INFO - PROCESS | 1681 | 11:37:44 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-dft) 11:37:44 INFO - PROCESS | 1681 | RSS: Main: 298594304 11:37:44 INFO - PROCESS | 1681 | 11:37:48 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-fft) 11:37:49 INFO - PROCESS | 1681 | RSS: Main: 385388544 11:37:49 INFO - PROCESS | 1681 | 11:37:51 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator) 11:37:51 INFO - PROCESS | 1681 | RSS: Main: 285204480 11:37:51 INFO - PROCESS | 1681 | 11:37:53 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:37:54 INFO - PROCESS | 1681 | RSS: Main: 290627584 11:37:54 INFO - PROCESS | 1681 | 11:37:57 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom) 11:37:57 INFO - PROCESS | 1681 | RSS: Main: 329281536 11:37:57 INFO - PROCESS | 1681 | 11:38:00 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate) 11:38:01 INFO - PROCESS | 1681 | RSS: Main: 330956800 11:38:01 INFO - PROCESS | 1681 | 11:38:04 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial) 11:38:04 INFO - PROCESS | 1681 | RSS: Main: 326774784 11:38:04 INFO - PROCESS | 1681 | 11:38:06 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:38:06 INFO - PROCESS | 1681 | RSS: Main: 319700992 11:38:06 INFO - PROCESS | 1681 | 11:38:08 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:38:09 INFO - PROCESS | 1681 | RSS: Main: 326008832 11:38:09 INFO - PROCESS | 1681 | 11:38:11 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:38:12 INFO - PROCESS | 1681 | RSS: Main: 329363456 11:38:12 INFO - PROCESS | 1681 | 11:38:15 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:38:15 INFO - PROCESS | 1681 | RSS: Main: 334454784 11:38:15 INFO - PROCESS | 1681 | 11:38:18 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:38:19 INFO - PROCESS | 1681 | RSS: Main: 332095488 11:38:19 INFO - PROCESS | 1681 | 11:38:20 INFO - PROCESS | 1681 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49276/tests/kraken/driver.html?testName=ai-astar) 11:38:21 INFO - PROCESS | 1681 | RSS: Main: 396173312 11:38:21 INFO - PROCESS | 1681 | 11:38:21 INFO - PROCESS | 1681 | __start_tp_report 11:38:21 INFO - PROCESS | 1681 | _x_x_mozilla_page_load 11:38:21 INFO - PROCESS | 1681 | _x_x_mozilla_page_load_details 11:38:21 INFO - PROCESS | 1681 | |i|pagename|runs| 11:38:21 INFO - PROCESS | 1681 | |0;ai-astar;112;109;110;113;111;111;119;111;113;114 11:38:21 INFO - PROCESS | 1681 | |1;audio-beat-detection;188;114;169;213;114;165;176;147;181;119 11:38:21 INFO - PROCESS | 1681 | |2;audio-dft;189;184;185;184;185;182;181;180;196;260 11:38:21 INFO - PROCESS | 1681 | |3;audio-fft;73;74;87;91;56;59;58;75;94;83 11:38:21 INFO - PROCESS | 1681 | |4;audio-oscillator;92;104;137;79;79;78;126;81;77;131 11:38:21 INFO - PROCESS | 1681 | |5;imaging-gaussian-blur;87;85;87;84;87;84;88;84;87;83 11:38:21 INFO - PROCESS | 1681 | |6;imaging-darkroom;95;97;94;95;150;125;93;93;93;93 11:38:21 INFO - PROCESS | 1681 | |7;imaging-desaturate;87;84;86;86;83;85;83;85;85;86 11:38:21 INFO - PROCESS | 1681 | |8;json-parse-financial;59;60;61;58;58;60;60;77;60;58 11:38:21 INFO - PROCESS | 1681 | |9;json-stringify-tinderbox;64;64;73;77;69;70;66;66;67;68 11:38:21 INFO - PROCESS | 1681 | |10;stanford-crypto-aes;80;82;82;80;88;91;106;76;83;82 11:38:21 INFO - PROCESS | 1681 | |11;stanford-crypto-ccm;143;138;137;134;134;141;149;137;176;141 11:38:21 INFO - PROCESS | 1681 | |12;stanford-crypto-pbkdf2;215;227;182;171;168;171;172;176;174;166 11:38:21 INFO - PROCESS | 1681 | |13;stanford-crypto-sha256-iterative;68;68;69;68;68;68;68;70;67;67 11:38:21 INFO - PROCESS | 1681 | __end_tp_report 11:38:21 INFO - PROCESS | 1681 | __start_cc_report 11:38:21 INFO - PROCESS | 1681 | _x_x_mozilla_cycle_collect,1943 11:38:21 INFO - PROCESS | 1681 | __end_cc_report 11:38:21 INFO - PROCESS | 1681 | __startTimestamp1461091101230__endTimestamp 11:38:21 INFO - PROCESS | 1681 | 11:38:21 INFO - PROCESS | 1681 | ------- Summary: start ------- 11:38:21 INFO - PROCESS | 1681 | Number of tests: 14 11:38:21 INFO - PROCESS | 1681 | 11:38:21 INFO - PROCESS | 1681 | [#0] ai-astar Cycles:10 Average:112.30 Median:112.50 stddev:2.79 (2.5%) stddev-sans-first:2.96 11:38:21 INFO - PROCESS | 1681 | Values: 112.0 109.0 110.0 113.0 111.0 111.0 119.0 111.0 113.0 114.0 11:38:21 INFO - PROCESS | 1681 | 11:38:21 INFO - PROCESS | 1681 | [#1] audio-beat-detection Cycles:10 Average:158.60 Median:172.50 stddev:34.09 (19.8%) stddev-sans-first:34.46 11:38:21 INFO - PROCESS | 1681 | Values: 188.0 114.0 169.0 213.0 114.0 165.0 176.0 147.0 181.0 119.0 11:38:21 INFO - PROCESS | 1681 | 11:38:21 INFO - PROCESS | 1681 | [#2] audio-dft Cycles:10 Average:192.60 Median:185.00 stddev:24.12 (13.0%) stddev-sans-first:25.55 11:38:21 INFO - PROCESS | 1681 | Values: 189.0 184.0 185.0 184.0 185.0 182.0 181.0 180.0 196.0 260.0 11:38:21 INFO - PROCESS | 1681 | 11:38:21 INFO - PROCESS | 1681 | [#3] audio-fft Cycles:10 Average:75.00 Median:79.00 stddev:13.89 (17.6%) stddev-sans-first:14.71 11:38:21 INFO - PROCESS | 1681 | Values: 73.0 74.0 87.0 91.0 56.0 59.0 58.0 75.0 94.0 83.0 11:38:21 INFO - PROCESS | 1681 | 11:38:21 INFO - PROCESS | 1681 | [#4] audio-oscillator Cycles:10 Average:98.40 Median:78.50 stddev:24.30 (31.0%) stddev-sans-first:25.67 11:38:21 INFO - PROCESS | 1681 | Values: 92.0 104.0 137.0 79.0 79.0 78.0 126.0 81.0 77.0 131.0 11:38:21 INFO - PROCESS | 1681 | 11:38:21 INFO - PROCESS | 1681 | [#5] imaging-gaussian-blur Cycles:10 Average:85.60 Median:87.00 stddev:1.78 (2.0%) stddev-sans-first:1.81 11:38:21 INFO - PROCESS | 1681 | Values: 87.0 85.0 87.0 84.0 87.0 84.0 88.0 84.0 87.0 83.0 11:38:21 INFO - PROCESS | 1681 | 11:38:21 INFO - PROCESS | 1681 | [#6] imaging-darkroom Cycles:10 Average:102.80 Median:93.50 stddev:19.26 (20.6%) stddev-sans-first:20.22 11:38:21 INFO - PROCESS | 1681 | Values: 95.0 97.0 94.0 95.0 150.0 125.0 93.0 93.0 93.0 93.0 11:38:21 INFO - PROCESS | 1681 | 11:38:21 INFO - PROCESS | 1681 | [#7] imaging-desaturate Cycles:10 Average:85.00 Median:85.50 stddev:1.33 (1.6%) stddev-sans-first:1.20 11:38:21 INFO - PROCESS | 1681 | Values: 87.0 84.0 86.0 86.0 83.0 85.0 83.0 85.0 85.0 86.0 11:38:21 INFO - PROCESS | 1681 | 11:38:21 INFO - PROCESS | 1681 | [#8] json-parse-financial Cycles:10 Average:61.10 Median:60.00 stddev:5.69 (9.5%) stddev-sans-first:5.98 11:38:21 INFO - PROCESS | 1681 | Values: 59.0 60.0 61.0 58.0 58.0 60.0 60.0 77.0 60.0 58.0 11:38:21 INFO - PROCESS | 1681 | 11:38:21 INFO - PROCESS | 1681 | [#9] json-stringify-tinderbox Cycles:10 Average:68.40 Median:68.50 stddev:4.09 (6.0%) stddev-sans-first:4.01 11:38:21 INFO - PROCESS | 1681 | Values: 64.0 64.0 73.0 77.0 69.0 70.0 66.0 66.0 67.0 68.0 11:38:21 INFO - PROCESS | 1681 | 11:38:21 INFO - PROCESS | 1681 | [#10] stanford-crypto-aes Cycles:10 Average:85.00 Median:82.00 stddev:8.49 (10.3%) stddev-sans-first:8.80 11:38:21 INFO - PROCESS | 1681 | Values: 80.0 82.0 82.0 80.0 88.0 91.0 106.0 76.0 83.0 82.0 11:38:21 INFO - PROCESS | 1681 | 11:38:21 INFO - PROCESS | 1681 | [#11] stanford-crypto-ccm Cycles:10 Average:143.00 Median:141.00 stddev:12.44 (8.8%) stddev-sans-first:13.19 11:38:21 INFO - PROCESS | 1681 | Values: 143.0 138.0 137.0 134.0 134.0 141.0 149.0 137.0 176.0 141.0 11:38:21 INFO - PROCESS | 1681 | 11:38:21 INFO - PROCESS | 1681 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.20 Median:175.00 stddev:21.10 (12.1%) stddev-sans-first:18.75 11:38:21 INFO - PROCESS | 1681 | Values: 215.0 227.0 182.0 171.0 168.0 171.0 172.0 176.0 174.0 166.0 11:38:21 INFO - PROCESS | 1681 | 11:38:21 INFO - PROCESS | 1681 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:68.00 stddev:0.88 (1.3%) stddev-sans-first:0.93 11:38:21 INFO - PROCESS | 1681 | Values: 68.0 68.0 69.0 68.0 68.0 68.0 68.0 70.0 67.0 67.0 11:38:21 INFO - PROCESS | 1681 | -------- Summary: end -------- 11:38:21 INFO - PROCESS | 1681 | 11:38:22 INFO - TEST-INFO | 1681: exit 0 11:38:22 INFO - TEST-OK | kraken | took 59134ms 11:38:22 INFO - SUITE-END | took 1173s 11:38:22 INFO - Completed test suite (00:19:33) 11:38:22 INFO - javascript benchmark 11:38:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3163.5571020057196, 3459.827008649567, 3483.1814523586936, 3488.6860182583564, 3534.8232588370574, 5387.057264978633, 5510.8071217507395, 5633.21135041094, 5652.406497317784, 5661.886762264758, 5633.464820842024, 5690.5447564194865, 5761.279840019997, 5785.190073389729, 5789.71051447427, 5044.924326135111, 5104.387473503185, 5130.871728206787, 5130.974345128269, 5201.219817027457, 5177.948220517785, 5214.7653355598895, 5232.555232805197, 5238.607104467161, 5279.894402111955, 5036.370453693288, 5086.440491545927, 5101.15830887903, 5125.308083408767, 5153.948460515403, 3775.8300876460626, 5110.897782044375, 5117.130087885032, 5126.026055049529, 5145.0, 5056.367954005749, 5095.898082038376, 5125.38495380553, 5130.846074617767, 5137.948620513785, 5461.863453413656, 5544.16837474378, 5593.300837395325, 5660.066089095294, 5669.546436285129, 6063.363346848561, 6138.8772224555705, 6158.230221222348, 6160.1375807387, 6176.81469555914, 6129.632222066691, 6159.630422174684, 6204.069389591552, 6208.317085120633, 6211.254649442096, 5718.42815718429, 5721.799737009184, 5825.300963884319, 5826.65040097587, 5831.0378787499585, 6235.095911092866, 6244.12582238487, 6258.217722784652, 6269.435750782361, 6308.684565771693, 6148.754049838046, 6164.7842325518395, 6169.259688837368, 6176.722047507873, 6178.505719542426, 6098.311206366262, 6132.4778862607845, 6254.249490061222, 6256.0303153011355, 6262.655553944577, 6175.166352542349, 6228.937710622927, 6230.0, 6240.781572644935, 6248.343923888017, 6137.447629713347, 6215.098810672427, 6247.812565623039, 6251.562390632612, 6271.466925311308, 4915.16442204826, 4921.261810728348, 4925.9014819704125, 4938.580220681282, 4986.6758660687665, 4398.758068306274, 4411.691181617321, 4412.029353542215, 4420.049689316748, 4468.955310446854, 5598.888022239533, 5714.171445140431, 5718.857028574319, 5719.8570035749435, 5747.655140691572, 6169.105479705508, 6228.750849965951, 6253.187085678833, 6260.186175797117, 6285.560010799291, 5677.37548869624, 5678.4605462480995, 5679.148127780783, 5708.286464191976, 5717.714114294268, 5306.893862122736, 5379.085555455582, 5379.112446446293, 5390.164524498709, 5394.190871369326, 6203.627782333075, 6242.656653883989, 6256.280527739277, 6258.906116408349, 6287.0, 6199.16311297969, 6241.063840423973, 6262.123302737529, 6272.498200143887, 6279.152314437492, 6170.382961703974, 6347.301796802441, 6363.0, 6366.29970703213, 6384.553081284265, 5162.561182299547, 6100.944524685144, 6166.013437849922, 6208.72060757258, 6217.347178546188, 6246.5002799775, 6253.687315634291, 6259.217597800275, 6272.372762723691, 6272.40412160844, 5989.371116032754, 6113.938860611337, 6128.295246046761, 6138.846528836636, 6144.938550614615, 6058.151858739868, 6112.837854638479, 6137.647534801459, 6138.480361557057, 6146.7091910699255, 4586.88532786683, 5898.882022359615, 5900.911486327795, 5947.881042379215, 5949.881002379841, 5987.700614969322, 6029.668368239789, 6094.299155597163, 6125.295591006913, 6151.938480615316, 6033.276006879027, 6034.939650603438, 6047.274327080779, 6057.242844644419, 6098.207233059674], "value": 5665.487783718814, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [532.6085327284447, 534.2200387434345, 540.8134193703171, 554.2157846646993, 560.1457776890239, 29544.0, 30062.098137055895, 38846.41730374047, 38892.61107388925, 39055.0, 36714.81642591783, 42217.57782422175, 42630.0, 42697.7865110674, 42705.57294427063, 8476.49141051539, 9445.0, 9680.951595242013, 9703.0, 9703.320767546275, 11488.71278218047, 11886.28682279066, 11888.524459021672, 11894.226875253082, 11894.988925941305, 11568.074554035657, 11657.475413606408, 11662.358570278631, 11664.35846028468, 11674.47464864079, 11522.366269855154, 11618.360990145538, 11631.88368116325, 11634.0, 11656.0, 11388.259763115371, 11504.02215811657, 11512.251703639236, 11513.366764827932, 11536.30782153065, 40344.596554034375, 40682.37270509176, 40751.592484075074, 40775.0, 40816.79591602023, 3224.0, 3267.313864088544, 3401.4727717203627, 3567.8037707926055, 3818.369968955119, 29070.709292907013, 29304.56043159354, 29312.120636380943, 29419.85290073536, 29759.25601859949, 2641.7886569074426, 2695.6495655564845, 2729.4541091781725, 2774.833509989407, 2790.4279622677304, 480639.0, 480925.0, 481472.0, 482057.0, 483199.0, 62977.68511157415, 63415.68292158602, 63421.36578634247, 63529.36470635327, 63599.0, 60272.69863650654, 68113.63772724518, 68123.65938170278, 68182.9772553412, 68245.65877170583, 16772.916135419488, 17308.740368894476, 17349.826501735075, 17366.21852016662, 17368.0, 13027.022973277042, 13050.804237936438, 13091.149075310075, 13098.476060957455, 13101.41043653038, 17860.19629116667, 18340.816591834177, 18349.0, 18406.81593184051, 18437.446876593724, 6317.8312012277875, 6414.61512309263, 6745.898811517831, 6940.930590694028, 6941.409980151744, 977.4868194197961, 983.680303901235, 994.5226291380178, 1004.7237009822358, 1112.071420364005, 28514.28714282118, 29487.0, 29789.0, 30248.395032099234, 30305.090847274616, 1010.166612544639, 1096.4024606589428, 1154.5670373609896, 1167.96496105117, 1194.6714653470192, 15325.31036103378, 15366.231688415535, 15551.066935983878, 15670.059796412252, 15693.372265109496, 614600.0, 702246.0, 711401.0, 711906.0, 712389.0, 53784.46215537873, 53858.461415386126, 54473.455265446835, 54489.0, 54611.180832287544, 1279.5329704657734, 1337.8193943817655, 1374.752544541992, 1498.2583621107403, 1522.3073501556764, 29983.550246746752, 30445.568028110523, 30700.84649576738, 30942.84528577433, 30972.0708378749, 53246.0, 53383.4661653394, 53910.730446347516, 53919.46080539301, 53988.19017714816, 400312.0, 403867.0, 404346.0, 404807.9759601183, 405360.0, 23864.64203036921, 26589.468210636067, 26685.93256269805, 27307.453850922473, 27732.8906843724, 42528.0, 42722.0, 43061.56938430575, 43507.0, 43994.560054399044, 34270.282682161065, 35842.4623630651, 35843.46234806428, 36441.81779091088, 36449.271014579026, 35450.93647190589, 36358.0, 36379.0, 37216.44175337426, 37281.81359093187, 36739.26521469502, 36771.81614091912, 37119.814400927826, 37868.0, 38218.808905955295, 30157.642906068842, 31832.045038648877, 31834.0, 31855.20361990876, 32002.35995280128, 21178.152873885487, 23291.0, 24486.75513244845, 24562.877185614674, 25170.37074073163], "value": 19978.98035672469, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.08057106086216, 392.64857952132843, 393.85427391865016, 397.6639739420189, 404.6075306952257, 13606.863931360684, 19787.802121978777, 20485.07817148228, 20499.897500512514, 20538.383848484533, 12904.483820647187, 15999.120048397333, 19725.802741972577, 20210.59578808423, 20356.796432035677, 5765.6540607563475, 6096.7561297548045, 6134.263888333407, 6138.877222455548, 6153.476954458876, 5952.107183922403, 6021.277446706401, 6048.939510604903, 6104.725287362057, 6124.234470691164, 5824.912626310609, 6015.548833837458, 6042.516598672096, 6049.243844519435, 6065.818025459222, 5619.634723742964, 5991.460768530831, 6042.516598672096, 6056.364081771395, 6060.727267272983, 5851.707414629252, 5864.44287792659, 5870.0314448116005, 5875.9118613220835, 5882.29412470502, 13964.860351396457, 14514.782278265835, 21157.788422115736, 25910.87044564784, 25924.870375648003, 2084.4059443058654, 2106.3680895731372, 2125.1499400239873, 2137.551114266006, 2297.7357603875603, 4204.579542045802, 9741.366811157252, 10132.594696212143, 10138.645147419878, 10616.853781143474, 1692.505046688079, 1736.366226327394, 1777.5200695812186, 1784.0633667324628, 1835.4218421197281, 120177.7982220184, 124340.0, 125238.12142817774, 125608.37195813963, 127805.36097319453, 11391.373474458818, 11625.0, 11633.41832908368, 11688.0, 11719.707007324885, 15320.23398830054, 15376.538703838904, 15378.923105384401, 15408.075515469107, 15413.691726165638, 7490.2135275796345, 7621.847563048708, 8090.231428014329, 8550.871736923951, 8702.608382622797, 8353.039400469024, 8372.246497815226, 8384.287335576422, 8468.364872634576, 8475.74572762818, 4528.841490547815, 4543.704659197141, 4553.430821147356, 4564.360989461478, 4615.169269531517, 1781.385422029398, 1824.5621050947677, 1877.6714075036814, 1945.2413558712115, 1964.7347608073012, 761.8362052158701, 920.5673333533229, 961.7547525381123, 1013.4476710192963, 1027.9331843430152, 17049.40327088571, 17420.0, 19596.020198989994, 19849.40451786449, 20221.797782021993, 927.1470247372433, 973.7468258252763, 985.0789511806483, 1032.6420756704908, 1033.3644808442905, 10123.076615443042, 10678.359298442118, 10699.73163701415, 10750.516226769814, 10829.133669306437, 510301.0, 525626.0, 582198.0, 602942.0, 604403.0, 139881.3005934964, 143641.0, 143926.0, 144442.27778861037, 148121.5187848129, 882.1531329923217, 1026.7227848481018, 1043.9895601043893, 1044.2794471814423, 1046.5029111172132, 16015.839841601435, 16559.668806624042, 17803.0, 18164.0, 18522.536936576693, 48778.75610621924, 50158.7492062552, 50954.0, 50993.0, 52806.471935280155, 65987.67006164939, 71093.64453177701, 73206.26793732207, 74173.0, 75557.22861728113, 7127.394171495273, 7882.487638303492, 8017.118117007241, 8116.0, 8401.285890699115, 8542.932133483315, 11828.053755699351, 12386.0, 12670.619881403572, 12838.871611284141, 10637.095846852795, 10924.2899211551, 10954.342739435659, 11035.448227588427, 11168.441577921234, 10634.787304253718, 10734.677959661223, 10815.297005694605, 11152.665420037412, 11154.442277885912, 10823.729406764578, 11027.448627568428, 11245.381504017358, 11294.60468883585, 11356.602518911799, 7370.373518251009, 7446.813829654302, 7592.506487078323, 7614.314711675976, 7717.729879454192, 10258.897411025795, 10899.29154604948, 11001.394923279297, 11340.922612351813, 11343.97904188627], "value": 9356.998063448304, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1388.2919710947415, 1594.09933387636, 1618.2960412220682, 1853.2123847364876, 1968.1438574220206, 7951.12537620862, 8658.653653853846, 8885.866711999326, 8889.977652569956, 8899.0, 8511.74464766058, 8707.303415726743, 8715.782105447366, 8715.956420217906, 8717.694880679177, 2394.928152155434, 2428.64784606232, 2429.4776623026046, 2429.987850060756, 2434.525267572824, 2381.452265978826, 2419.830611857171, 2421.588329983907, 2424.0425032112307, 2431.258466167827, 2115.7038014677955, 2307.746147923727, 2405.8797060147012, 2408.7952524035477, 2419.733829278778, 2376.9762302376926, 2396.25716028031, 2406.0, 2408.2534414331526, 2414.5895197816412, 2359.3275916363755, 2374.3233178544033, 2383.249276477904, 2387.85672859629, 2391.545606334791, 8511.702090426805, 8776.868346974801, 8862.42394244372, 8867.512286824222, 8887.422317549403, 4227.72519786216, 4866.829660961849, 5178.093833579109, 5341.973290133564, 5387.24578559002, 3126.640436349803, 3350.1289664687356, 3417.470292104703, 3427.1260828488826, 3529.1353618363664, 998.8862418403501, 1005.4771518810178, 1020.2552136940001, 1023.5598692562195, 1026.3944272879037, 11154.274972126786, 11477.885221147848, 11478.598249061242, 11483.138764592606, 11513.88486115145, 3686.5391826021746, 4409.272470042407, 4533.77331133442, 4559.726416415026, 4574.839880604163, 9026.413283136575, 9028.593713282919, 9042.819143617091, 9043.728688139367, 9045.954770226106, 4145.854895078658, 4804.015176888729, 5068.290439338495, 5197.870053248624, 5237.423883372826, 4347.0, 4517.073999830027, 4717.669763116617, 4869.63477739171, 4899.4365647950945, 3250.431174544445, 3671.7148997851073, 3699.112213068844, 3763.8682646107804, 3815.465834783133, 1159.3333833045863, 1163.5694792926672, 1171.543098191706, 1174.172208592957, 1180.6399048290355, 648.8215740671353, 766.2414758130104, 903.7424334064891, 909.1999040844015, 930.4789317981952, 5054.267131265946, 5095.745212739348, 5559.221708960748, 5648.63283886546, 5719.685417302088, 555.0924238869477, 560.7616762875844, 572.7136431784108, 574.3423779772221, 668.0346898731286, 3124.718775310233, 3692.409214525663, 3814.7520411173186, 3848.8652897148463, 3912.4522566840665, 10136.797264054678, 10612.57549698019, 10678.893211067945, 10783.6225732099, 10836.945815270874, 10562.630307939186, 10565.9471702641, 10573.841392379121, 10708.250422470352, 10750.73123171927, 7719.845603087907, 8090.757277281691, 8150.796230094295, 8156.592170391456, 8208.015038195335, 5749.338826035059, 5814.389489103583, 5819.359870414166, 5887.0580707086665, 6051.364606716232, 9574.90425095768, 9780.217582593234, 9897.010298970334, 9903.851442228228, 9908.702738917844, 9331.346805723535, 9991.050850169222, 10081.99180081986, 10083.890772014925, 10090.99090090985, 7834.764957051298, 8176.8364632705825, 8196.0, 8206.179382061984, 8208.794780130545, 6411.807645770635, 7900.644470998704, 7972.043354797462, 7982.401319901147, 7982.720604778805, 7300.96349518249, 7309.488335816442, 7319.158296795937, 7423.517471364344, 7472.178060413242, 7127.928720712934, 7305.671244794104, 7318.012068370702, 7379.188289288281, 7453.552786832807, 6688.3646053624825, 6754.155730533684, 6777.356151165632, 6779.627120508222, 6792.0830687856505, 4604.286335617984, 4750.596199323097, 4783.545563171493, 4826.855194344175, 4968.453470118357, 4079.5920407958965, 4801.159797035574, 4819.132556139928, 4833.250846118857, 5041.899162016666], "value": 4416.676853368274, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [314.5062252263946, 319.0842282648798, 324.1183979575554, 330.06755914541424, 335.1403649638678, 5289.735513224337, 5884.557159344688, 5897.823065308037, 5898.262717160355, 5935.9703201483935, 5287.709175995319, 5473.28847249858, 5730.656160630365, 5828.562857785672, 5858.589898707092, 3282.7853694133205, 3777.9622203777885, 3992.7005474589523, 4004.8786339824896, 4430.9556904430865, 3575.4100573405485, 4343.196508645895, 4376.168527979689, 4400.559944005606, 4410.095930334273, 4370.1915145697985, 4389.736615803062, 4391.758453285068, 4409.9118017639785, 4410.889727756799, 3643.599204087548, 4112.280350938574, 4133.689973252018, 4165.604267594574, 4335.761533115678, 3608.9278214435826, 3788.71584631151, 3790.550598954965, 3805.315043292206, 3827.162961778362, 5124.692518448867, 5414.938795903073, 5600.747966341524, 5658.151277308396, 5673.886522269573, 4415.690901636886, 4809.75951202442, 5057.367829021372, 5093.770780314858, 5129.6409251352425, 5061.75986883216, 5073.898522029539, 6135.171751813493, 6137.079438084278, 6141.570090093695, 4121.216968775923, 4256.7445953242595, 4276.498248524616, 4281.079567892918, 4292.248856450109, 6134.079888016789, 6414.61512309263, 6507.316731743146, 6524.217093948757, 6537.673116344164, 4224.619784219435, 4287.828486860491, 4311.956880431218, 4359.215341238608, 4389.978050109729, 3911.198204368098, 4430.654855584014, 4442.0, 4445.73325600465, 4449.7330160190495, 4036.0, 4328.5022222444195, 5124.180131179068, 5130.4356520782685, 5175.948240517547, 5924.289085309704, 6034.275886893601, 6251.343608921089, 6256.593321434092, 6327.841803954846, 1464.3526033212636, 1513.2585033333587, 1535.6007438066183, 1537.2621141851973, 1539.5612250508775, 868.2663149638545, 956.899565499667, 978.6770365779275, 1011.5195282240877, 1027.2039169643585, 5329.520343169056, 5492.752826122834, 5750.712464376765, 5892.351841297454, 5917.721677154241, 6500.992346186349, 6511.958086706199, 6527.379898909596, 6533.379328963741, 6550.737970481223, 5019.272205530177, 5229.581633469314, 5906.202662640574, 5941.346451890288, 5949.8215053548465, 3044.786864919479, 3063.6979283804294, 3190.7766456348295, 3261.62491313502, 3305.8677652893616, 5502.724863756796, 6518.380753828474, 6721.0, 6760.830979225559, 6793.5244532882225, 5453.64551304164, 6024.457798798129, 6606.7026983785845, 6618.338166183439, 6664.433523150587, 5054.047033906719, 5299.8410047698635, 6688.0, 6697.0, 6730.831729206809, 4829.468758436639, 6364.713587888464, 6387.5209359297305, 6437.8175568667675, 6471.288158302677, 6332.0, 6380.712867921047, 6393.104965304768, 6400.743970241138, 6425.1128067518475, 5839.532837373085, 5859.765609375577, 6399.232092148972, 6700.363465470968, 6717.529772915849, 3863.787491687984, 4182.749035057785, 4190.1200747843295, 4221.577842215754, 4242.0, 5358.892822143614, 6236.376362363727, 6482.35176482348, 6491.740330386731, 6501.83882315328, 6252.874942501216, 6281.748730050746, 6284.968575157278, 6308.116863639002, 6311.495080393649, 5121.871953201051, 5909.911351329648, 5912.201852749824, 6215.689215539295, 6289.685515724287, 5918.858698090511, 6133.785317513865, 6133.877322453615, 6138.600990935575, 6168.537359697951, 5533.529649979636, 6127.835711214855, 6128.662923539248, 6182.0, 6233.594816336924, 5099.5665368442615, 5228.398734145622, 5376.435474275301, 6099.2985806632805, 6228.56400051992], "value": 4494.261936244492, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [714.932081452262, 725.4087918346547, 730.2843213650625, 738.8633102875968, 768.8577613141571, 11886.524539018428, 15172.0, 15184.0, 15199.468018619351, 15199.544013679593, 13030.218186908793, 15497.845021549781, 16054.03675779454, 16075.83924160758, 16083.597910052225, 726.8400951790597, 917.1882883647986, 927.6753136402273, 939.506758951549, 941.0072373645801, 740.8518296340753, 828.353883970504, 876.7325965580527, 937.5827756648295, 937.8874535055803, 968.5351031504882, 975.6487664440796, 978.021978021978, 980.7302991677275, 980.8675828763097, 748.9775306740806, 939.2486011191088, 960.6445615122415, 970.4177493503912, 970.8834939807233, 721.8267615772229, 725.6661935509671, 726.3862036579083, 727.7271023366237, 727.9781606551812, 12626.30555319457, 15094.094354338775, 15143.0, 15160.620984475367, 15251.542453726295, 10950.507227174796, 11288.096952243883, 12173.817392739116, 12399.0, 12663.683407914783, 9808.362456440309, 10199.54102065409, 10209.948950255275, 10475.47622618866, 10647.84028239577, 3400.353932752769, 3444.1045328214595, 3461.549998500204, 3508.6842184203288, 3715.034091136297, 90248.09751902462, 91617.54191229068, 93317.99999999932, 93728.53135734345, 93738.53130734371, 8009.639566219534, 9264.861027084598, 9390.389624674373, 9407.576659050359, 9641.710748677551, 21474.463138421663, 21513.13947442116, 21529.784702152778, 21547.67678484824, 21574.676379854314, 7382.261773822682, 7472.887906681404, 7497.212792656802, 7507.512011719221, 7611.695532178651, 4072.1477263094116, 4146.981365964878, 4154.044569748914, 4167.354060120686, 4181.540030596632, 3272.378248132847, 3369.22507823202, 3369.713574346159, 3369.730421566269, 3372.2581032172884, 611.5718996702237, 622.9781957631461, 623.9875202495924, 628.6353914729445, 632.0645444741716, 495.8834673851642, 544.1130956540892, 573.9697243448021, 608.3369127650882, 610.9602875813058, 7754.0, 7831.177726338652, 7969.561674107979, 8098.959505202554, 8144.837103257902, 565.1212364772817, 574.379669956446, 578.9507891829157, 621.7171187109855, 629.5467263570222, 3662.4506324051604, 3744.8127593620757, 3791.9431208531896, 3894.9610503894596, 3930.3907894275862, 92723.0, 95573.04426955641, 97802.0, 98078.50960745293, 98183.0, 78090.6095469519, 78100.0, 78428.02007718924, 78894.0, 79474.2841145414, 60427.0, 61779.38220617826, 70247.2975270251, 72106.0, 72548.63725681338, 7651.579163146081, 7900.644470998934, 7970.322522585413, 7988.48506657069, 8341.624626891684, 42999.57000429956, 43389.566104338555, 43490.0, 45069.54930450654, 45269.0, 47780.28329575129, 63655.72688546296, 63812.0, 63901.0414843787, 64102.3589764115, 10965.396903170402, 11020.724481887697, 11160.609378672032, 11180.55277788912, 11223.0, 11434.885651143715, 11715.882841171479, 11791.528338866348, 11878.406079695807, 12195.085368597212, 11235.943820280847, 11326.150538709466, 11560.421978900853, 11616.186866919235, 11676.007539359249, 11274.379909105079, 11280.717982050515, 11287.163256660235, 11488.540458381905, 11574.363410012531, 11067.280626759235, 11156.386398748147, 11211.831822522505, 11506.827397588875, 11940.641780746244, 9426.387284826464, 9608.27937904646, 9641.951790241004, 9769.120779129913, 9853.802923941625, 9578.0, 9756.07317304855, 9858.162056225014, 9862.802743944938, 9932.950335248277], "value": 6856.875700405951, "unit": "score"}], "name": "dromaeo_css", "value": 7241.220669637429, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 109.0, 110.0, 113.0, 111.0, 111.0, 119.0, 111.0, 113.0, 114.0], "value": 112.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 114.0, 169.0, 213.0, 114.0, 165.0, 176.0, 147.0, 181.0, 119.0], "value": 158.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 184.0, 185.0, 184.0, 185.0, 182.0, 181.0, 180.0, 196.0, 260.0], "value": 192.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 87.0, 91.0, 56.0, 59.0, 58.0, 75.0, 94.0, 83.0], "value": 75.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 104.0, 137.0, 79.0, 79.0, 78.0, 126.0, 81.0, 77.0, 131.0], "value": 98.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 87.0, 84.0, 87.0, 84.0, 88.0, 84.0, 87.0, 83.0], "value": 85.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 97.0, 94.0, 95.0, 150.0, 125.0, 93.0, 93.0, 93.0, 93.0], "value": 102.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 86.0, 86.0, 83.0, 85.0, 83.0, 85.0, 85.0, 86.0], "value": 85.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 61.0, 58.0, 58.0, 60.0, 60.0, 77.0, 60.0, 58.0], "value": 61.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 73.0, 77.0, 69.0, 70.0, 66.0, 66.0, 67.0, 68.0], "value": 68.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 82.0, 82.0, 80.0, 88.0, 91.0, 106.0, 76.0, 83.0, 82.0], "value": 85.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 138.0, 137.0, 134.0, 134.0, 141.0, 149.0, 137.0, 176.0, 141.0], "value": 143.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [215.0, 227.0, 182.0, 171.0, 168.0, 171.0, 172.0, 176.0, 174.0, 166.0], "value": 182.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 69.0, 68.0, 68.0, 68.0, 68.0, 70.0, 67.0, 67.0], "value": 68.1, "unit": "score"}], "name": "kraken", "value": 1518.1, "alertThreshold": 2.0}]} 11:38:22 INFO - Return code: 0 11:38:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 11:38:22 ERROR - # TBPL SUCCESS # 11:38:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:38:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:38:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:38:22 INFO - 2016-04-19 11:38:22.564 screenresolution[1685:10055] starting screenresolution argv=screenresolution get 11:38:22 INFO - 2016-04-19 11:38:22.567 screenresolution[1685:10055] Display 0: 1600x1200x32@60 11:38:22 INFO - 2016-04-19 11:38:22.573 screenresolution[1686:10058] starting screenresolution argv=screenresolution list 11:38:22 INFO - Available Modes on Display 0 11:38:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:38:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:38:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:38:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:38:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:38:22 INFO - Intel Iris: 11:38:22 INFO - Chipset Model: Intel Iris 11:38:22 INFO - Type: GPU 11:38:22 INFO - Bus: Built-In 11:38:22 INFO - VRAM (Dynamic, Max): 1536 MB 11:38:22 INFO - Vendor: Intel (0x8086) 11:38:22 INFO - Device ID: 0x0a2e 11:38:22 INFO - Revision ID: 0x0009 11:38:22 INFO - Displays: 11:38:22 INFO - Display: 11:38:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:38:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:38:22 INFO - Display Serial Number: 1600x1200 60 11:38:22 INFO - Main Display: Yes 11:38:22 INFO - Mirror: Off 11:38:22 INFO - Online: Yes 11:38:22 INFO - Rotation: Supported 11:38:22 INFO - Return code: 0 11:38:22 INFO - Running post-action listener: _resource_record_post_action 11:38:22 INFO - Running post-run listener: _resource_record_post_run 11:38:23 INFO - Total resource usage - Wall time: 1209s; CPU: 53.0%; Read bytes: 37869056; Write bytes: 697967616; Read time: 407; Write time: 1957 11:38:23 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 400628224; Write bytes: 396805632; Read time: 29711; Write time: 697 11:38:23 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 30735872; Write bytes: 296150528; Read time: 308; Write time: 1167 11:38:23 INFO - Running post-run listener: _upload_blobber_files 11:38:23 INFO - Blob upload gear active. 11:38:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:38:23 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 11:38:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:38:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:38:24 INFO - (blobuploader) - INFO - Open directory for files ... 11:38:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:38:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:38:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:38:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:38:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:38:25 INFO - (blobuploader) - INFO - Done attempting. 11:38:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:38:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:38:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:38:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:38:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:38:26 INFO - (blobuploader) - INFO - Done attempting. 11:38:26 INFO - (blobuploader) - INFO - Iteration through files over. 11:38:26 INFO - Return code: 0 11:38:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:38:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:38:26 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3982b2bd8b278f2a456d08ad878ba0ac819f08930168c0a06d50115427e43fb98ef9ddef83821add36ba7a213fdf76c4e908ebc31b5109cf9948fd46e7f79e60", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/74e8271f5d6f670ca6fc3fee676dde2af590249def73c17837784c761bb3b49b95c53a7345756ab359b7ee6ca661133f856afbd9f606c1fa33d745803a72b7c6"} 11:38:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:38:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:38:26 INFO - Contents: 11:38:26 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3982b2bd8b278f2a456d08ad878ba0ac819f08930168c0a06d50115427e43fb98ef9ddef83821add36ba7a213fdf76c4e908ebc31b5109cf9948fd46e7f79e60", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/74e8271f5d6f670ca6fc3fee676dde2af590249def73c17837784c761bb3b49b95c53a7345756ab359b7ee6ca661133f856afbd9f606c1fa33d745803a72b7c6"} 11:38:26 INFO - Running post-run listener: copy_logs_to_upload_dir 11:38:26 INFO - Copying logs to upload dir... 11:38:26 INFO - mkdir: /builds/slave/test/build/upload/logs 11:38:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.735986 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-04-19 11:38:26.548300) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-19 11:38:26.552028) ========= 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.zic1XNgTvr/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.At6aEjL8km/Listeners TMPDIR=/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3982b2bd8b278f2a456d08ad878ba0ac819f08930168c0a06d50115427e43fb98ef9ddef83821add36ba7a213fdf76c4e908ebc31b5109cf9948fd46e7f79e60", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/74e8271f5d6f670ca6fc3fee676dde2af590249def73c17837784c761bb3b49b95c53a7345756ab359b7ee6ca661133f856afbd9f606c1fa33d745803a72b7c6"} build_url:https://queue.taskcluster.net/v1/task/SFZbYUUXTjeC3HwIGcnm_w/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010700 build_url: 'https://queue.taskcluster.net/v1/task/SFZbYUUXTjeC3HwIGcnm_w/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3982b2bd8b278f2a456d08ad878ba0ac819f08930168c0a06d50115427e43fb98ef9ddef83821add36ba7a213fdf76c4e908ebc31b5109cf9948fd46e7f79e60", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/74e8271f5d6f670ca6fc3fee676dde2af590249def73c17837784c761bb3b49b95c53a7345756ab359b7ee6ca661133f856afbd9f606c1fa33d745803a72b7c6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-19 11:38:26.583578) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 11:38:26.584334) ========= 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.zic1XNgTvr/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.At6aEjL8km/Listeners TMPDIR=/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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.005153 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 11:38:26.637269) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-19 11:38:26.637582) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-19 11:38:26.637975) ========= ========= Total master_lag: 3.24 =========