builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0375 starttime: 1464638304.22 results: success (0) buildid: 20160530120549 builduid: 7997d47d310b490c9d491deb39fe4327 revision: 3c7a33fe0b6472bf47264c2390c5bc19f1a6dce4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 12:58:24.223524) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 12:58:24.223938) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 12:58:24.224210) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 12:58:24.253681) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 12:58:24.254002) ========= 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.tNXkH2dPO2/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.SyAF0xPJk1/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-30 12:58:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 196M=0s 2016-05-30 12:58:24 (196 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.446339 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 12:58:24.722949) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 12:58:24.723475) ========= 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.tNXkH2dPO2/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.SyAF0xPJk1/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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.026269 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 12:58:24.775217) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-30 12:58:24.776043) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3c7a33fe0b6472bf47264c2390c5bc19f1a6dce4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3c7a33fe0b6472bf47264c2390c5bc19f1a6dce4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.tNXkH2dPO2/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.SyAF0xPJk1/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-30 12:58:24,859 truncating revision to first 12 chars 2016-05-30 12:58:24,859 Setting DEBUG logging. 2016-05-30 12:58:24,860 attempt 1/10 2016-05-30 12:58:24,860 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/3c7a33fe0b64?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-30 12:58:25,986 unpacking tar archive at: mozilla-aurora-3c7a33fe0b64/testing/mozharness/ program finished with exit code 0 elapsedTime=1.395529 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-30 12:58:26.211851) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 12:58:26.212170) ========= script_repo_revision: 3c7a33fe0b6472bf47264c2390c5bc19f1a6dce4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 12:58:26.212583) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 12:58:26.215924) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 12:58:26.232511) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-05-30 12:58:26.232873) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.tNXkH2dPO2/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.SyAF0xPJk1/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 12:58:26 INFO - MultiFileLogger online at 20160530 12:58:26 in /builds/slave/test 12:58:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 12:58:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:58:26 INFO - {'append_to_log': False, 12:58:26 INFO - 'base_work_dir': '/builds/slave/test', 12:58:26 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 12:58:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:58:26 INFO - 'branch': 'Mozilla-Aurora', 12:58:26 INFO - 'buildbot_json_path': 'buildprops.json', 12:58:26 INFO - 'config_files': ('talos/mac_config.py',), 12:58:26 INFO - 'default_actions': ('clobber', 12:58:26 INFO - 'read-buildbot-config', 12:58:26 INFO - 'download-and-extract', 12:58:26 INFO - 'populate-webroot', 12:58:26 INFO - 'create-virtualenv', 12:58:26 INFO - 'install', 12:58:26 INFO - 'run-tests'), 12:58:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:58:26 INFO - 'download_minidump_stackwalk': True, 12:58:26 INFO - 'download_symbols': 'ondemand', 12:58:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:58:26 INFO - 'tooltool.py': '/tools/tooltool.py', 12:58:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:58:26 INFO - '/tools/misc-python/virtualenv.py')}, 12:58:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:58:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:58:26 INFO - 'installer_path': 'installer.exe', 12:58:26 INFO - 'log_level': 'info', 12:58:26 INFO - 'log_name': 'talos', 12:58:26 INFO - 'log_to_console': True, 12:58:26 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:58:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:58:26 INFO - 'opt_config_files': (), 12:58:26 INFO - 'pip_index': False, 12:58:26 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:58:26 INFO - 'cmd': ('bash', 12:58:26 INFO - '-c', 12:58:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:58:26 INFO - 'enabled': True, 12:58:26 INFO - 'halt_on_failure': False, 12:58:26 INFO - 'name': 'check_screen_resolution'},), 12:58:26 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:58:26 INFO - 'cmd': ('bash', 12:58:26 INFO - '-c', 12:58:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:58:26 INFO - 'enabled': True, 12:58:26 INFO - 'halt_on_failure': False, 12:58:26 INFO - 'name': 'check_screen_resolution'},), 12:58:26 INFO - 'run_cmd_checks_enabled': True, 12:58:26 INFO - 'sps_profile': False, 12:58:26 INFO - 'sps_profile_interval': 0, 12:58:26 INFO - 'suite': 'dromaeojs', 12:58:26 INFO - 'system_bits': '32', 12:58:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:58:26 INFO - 'title': 't-yosemite-r7-0375', 12:58:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:58:26 INFO - 'use_talos_json': True, 12:58:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:58:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:58:26 INFO - 'work_dir': 'build'} 12:58:26 INFO - ##### 12:58:26 INFO - ##### Running clobber step. 12:58:26 INFO - ##### 12:58:26 INFO - Running pre-action listener: _resource_record_pre_action 12:58:26 INFO - Running main action method: clobber 12:58:26 INFO - rmtree: /builds/slave/test/build 12:58:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:58:28 INFO - Running post-action listener: _resource_record_post_action 12:58:28 INFO - ##### 12:58:28 INFO - ##### Running read-buildbot-config step. 12:58:28 INFO - ##### 12:58:28 INFO - Running pre-action listener: _resource_record_pre_action 12:58:28 INFO - Running main action method: read_buildbot_config 12:58:28 INFO - Using buildbot properties: 12:58:28 INFO - { 12:58:28 INFO - "project": "", 12:58:28 INFO - "product": "firefox", 12:58:28 INFO - "script_repo_revision": "production", 12:58:28 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 12:58:28 INFO - "repository": "", 12:58:28 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 12:58:28 INFO - "buildid": "20160530120549", 12:58:28 INFO - "pgo_build": "False", 12:58:28 INFO - "basedir": "/builds/slave/test", 12:58:28 INFO - "buildnumber": 251, 12:58:28 INFO - "slavename": "t-yosemite-r7-0375", 12:58:28 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 12:58:28 INFO - "platform": "yosemite_r7", 12:58:28 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 12:58:28 INFO - "branch": "mozilla-aurora", 12:58:28 INFO - "repo_path": "releases/mozilla-aurora", 12:58:28 INFO - "revision": "3c7a33fe0b6472bf47264c2390c5bc19f1a6dce4", 12:58:28 INFO - "stage_platform": "macosx64", 12:58:28 INFO - "builduid": "7997d47d310b490c9d491deb39fe4327", 12:58:28 INFO - "slavebuilddir": "test" 12:58:28 INFO - } 12:58:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 12:58:28 INFO - Running post-action listener: _resource_record_post_action 12:58:28 INFO - ##### 12:58:28 INFO - ##### Running download-and-extract step. 12:58:28 INFO - ##### 12:58:28 INFO - Running pre-action listener: _resource_record_pre_action 12:58:28 INFO - Running main action method: download_and_extract 12:58:28 INFO - mkdir: /builds/slave/test/build/tests 12:58:28 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')]} 12:58:28 INFO - https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 12:58:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 12:58:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 12:58:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 12:58:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 12:58:31 INFO - Downloaded 1199 bytes. 12:58:31 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 12:58:31 INFO - Using the following test package requirements: 12:58:31 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 12:58:31 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:58:31 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 12:58:31 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:58:31 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 12:58:31 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:58:31 INFO - u'jsshell-mac.zip'], 12:58:31 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:58:31 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 12:58:31 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 12:58:31 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:58:31 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 12:58:31 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:58:31 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 12:58:31 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:58:31 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 12:58:31 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:58:31 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 12:58:31 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 12:58:31 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')]} 12:58:31 INFO - https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:58:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 12:58:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 12:58:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 12:58:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 12:58:32 INFO - Downloaded 18463801 bytes. 12:58:32 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:58:32 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 12:58:35 INFO - Return code: 0 12:58:35 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 12:58:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:58:35 INFO - https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:58:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 12:58:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 12:58:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 12:58:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 12:58:37 INFO - Downloaded 18463801 bytes. 12:58:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:58:37 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 12:58:40 INFO - Return code: 0 12:58:40 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')]} 12:58:40 INFO - https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 12:58:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 12:58:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 12:58:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 12:58:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 12:58:42 INFO - Downloaded 10840145 bytes. 12:58:42 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:58:42 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 12:58:42 INFO - Return code: 0 12:58: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')]} 12:58:42 INFO - https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 12:58:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 12:58:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 12:58:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 12:58:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 12:58:45 INFO - Downloaded 114159654 bytes. 12:58:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 12:58:45 INFO - mkdir: /builds/slave/test/properties 12:58:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:58:45 INFO - Writing to file /builds/slave/test/properties/build_url 12:58:45 INFO - Contents: 12:58:45 INFO - build_url:https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 12:58:47 INFO - Running post-action listener: _resource_record_post_action 12:58:47 INFO - Running post-action listener: set_extra_try_arguments 12:58:47 INFO - ##### 12:58:47 INFO - ##### Running populate-webroot step. 12:58:47 INFO - ##### 12:58:47 INFO - Running pre-action listener: _resource_record_pre_action 12:58:47 INFO - Running main action method: populate_webroot 12:58:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:58:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:58:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:58:47 INFO - '--tppagecycles', 12:58:47 INFO - '7'], 12:58:47 INFO - 'tests': ['tsvgm']}}, 12:58:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:58:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:58:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:58:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:58:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:58:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:58:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:58:47 INFO - 'talos_options': ['--disable-e10s'], 12:58:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:58:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:58:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:58:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:58:47 INFO - 'talos_options': ['--disable-e10s'], 12:58:47 INFO - 'tests': ['damp', 'tps']}, 12:58:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:58:47 INFO - 'tests': ['damp', 'tps']}, 12:58:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:58:47 INFO - 'tests': ['dromaeo_dom']}, 12:58:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:58:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:58:47 INFO - 'tests': ['a11yr', 12:58:47 INFO - 'ts_paint', 12:58:47 INFO - 'tpaint', 12:58:47 INFO - 'sessionrestore', 12:58:47 INFO - 'sessionrestore_no_auto_restore', 12:58:47 INFO - 'tabpaint']}, 12:58:47 INFO - 'other-e10s': {'tests': ['a11yr', 12:58:47 INFO - 'ts_paint', 12:58:47 INFO - 'tpaint', 12:58:47 INFO - 'sessionrestore', 12:58:47 INFO - 'sessionrestore_no_auto_restore', 12:58:47 INFO - 'tabpaint']}, 12:58:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:58:47 INFO - 'ts_paint', 12:58:47 INFO - 'tpaint', 12:58:47 INFO - 'sessionrestore', 12:58:47 INFO - 'sessionrestore_no_auto_restore', 12:58:47 INFO - 'tabpaint']}, 12:58:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:58:47 INFO - 'ts_paint', 12:58:47 INFO - 'tpaint', 12:58:47 INFO - 'sessionrestore', 12:58:47 INFO - 'sessionrestore_no_auto_restore', 12:58:47 INFO - 'tabpaint']}, 12:58:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:58:47 INFO - 'tests': ['a11yr', 12:58:47 INFO - 'ts_paint', 12:58:47 INFO - 'tpaint', 12:58:47 INFO - 'sessionrestore', 12:58:47 INFO - 'sessionrestore_no_auto_restore', 12:58:47 INFO - 'tabpaint']}, 12:58:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:58:47 INFO - 'tests': ['a11yr', 12:58:47 INFO - 'ts_paint', 12:58:47 INFO - 'tpaint', 12:58:47 INFO - 'sessionrestore', 12:58:47 INFO - 'sessionrestore_no_auto_restore', 12:58:47 INFO - 'tabpaint']}, 12:58:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:58:47 INFO - 'tests': ['tsvgx', 12:58:47 INFO - 'tsvgr_opacity', 12:58:47 INFO - 'tart', 12:58:47 INFO - 'tscrollx', 12:58:47 INFO - 'cart']}, 12:58:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:58:47 INFO - 'tsvgr_opacity', 12:58:47 INFO - 'tart', 12:58:47 INFO - 'tscrollx', 12:58:47 INFO - 'cart']}, 12:58:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:58:47 INFO - 'talos_options': ['--disable-e10s'], 12:58:47 INFO - 'tests': ['tp5o']}, 12:58:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:58:47 INFO - 'tests': ['tp5o']}, 12:58:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:58:47 INFO - 'talos_options': ['--disable-e10s', 12:58:47 INFO - '--xperf_path', 12:58:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:58:47 INFO - 'tests': ['tp5n']}, 12:58:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:58:47 INFO - 'talos_options': ['--xperf_path', 12:58:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:58:47 INFO - 'tests': ['tp5n']}}, 12:58:47 INFO - 'talos.zip': {'path': '', 12:58:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:58:47 INFO - Running post-action listener: _resource_record_post_action 12:58:47 INFO - ##### 12:58:47 INFO - ##### Running create-virtualenv step. 12:58:47 INFO - ##### 12:58:47 INFO - Running pre-action listener: _resource_record_pre_action 12:58:47 INFO - Running main action method: create_virtualenv 12:58:47 INFO - Creating virtualenv /builds/slave/test/build/venv 12:58:47 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 12:58:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:58:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:58:47 INFO - Using real prefix '/tools/python27' 12:58:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:58:48 INFO - Installing distribute.............................................................................................................................................................................................done. 12:58:51 INFO - Installing pip.................done. 12:58:51 INFO - Return code: 0 12:58:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:58:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:58:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:58:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:58:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:58:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58:51 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 0x10bd15a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd25420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb55b5903c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbb4e00>, '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 0x10ba1ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbafb20>, '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.tNXkH2dPO2/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.SyAF0xPJk1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 12:58:51 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 12:58:51 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 12:58:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tNXkH2dPO2/Render', 12:58:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:58:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:58:51 INFO - 'HOME': '/Users/cltbld', 12:58:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:58:51 INFO - 'LOGNAME': 'cltbld', 12:58:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:58:51 INFO - 'MOZ_NO_REMOTE': '1', 12:58:51 INFO - 'NO_EM_RESTART': '1', 12:58:51 INFO - 'PAGER': '/bin/cat', 12:58:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:58:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:58:51 INFO - 'PWD': '/builds/slave/test', 12:58:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:58:51 INFO - 'SHELL': '/bin/bash', 12:58:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SyAF0xPJk1/Listeners', 12:58:51 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 12:58:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:58:51 INFO - 'USER': 'cltbld', 12:58:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:58:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:58:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:58:51 INFO - 'XPC_FLAGS': '0x0', 12:58:51 INFO - 'XPC_SERVICE_NAME': '0', 12:58:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:58:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:58:51 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 12:58:51 INFO - Cleaning up... 12:58:51 INFO - Return code: 0 12:58:51 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 12:58:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:58:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:58:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:58:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:58:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58:51 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 0x10bd15a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd25420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb55b5903c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbb4e00>, '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 0x10ba1ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbafb20>, '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.tNXkH2dPO2/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.SyAF0xPJk1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 12:58:51 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 12:58:51 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 12:58:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tNXkH2dPO2/Render', 12:58:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:58:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:58:51 INFO - 'HOME': '/Users/cltbld', 12:58:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:58:51 INFO - 'LOGNAME': 'cltbld', 12:58:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:58:51 INFO - 'MOZ_NO_REMOTE': '1', 12:58:51 INFO - 'NO_EM_RESTART': '1', 12:58:51 INFO - 'PAGER': '/bin/cat', 12:58:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:58:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:58:51 INFO - 'PWD': '/builds/slave/test', 12:58:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:58:51 INFO - 'SHELL': '/bin/bash', 12:58:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SyAF0xPJk1/Listeners', 12:58:51 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 12:58:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:58:51 INFO - 'USER': 'cltbld', 12:58:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:58:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:58:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:58:51 INFO - 'XPC_FLAGS': '0x0', 12:58:51 INFO - 'XPC_SERVICE_NAME': '0', 12:58:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:58:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:58:51 INFO - Downloading/unpacking psutil>=0.7.1 12:58:51 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 12:58:51 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 12:58:51 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 12:58:51 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 12:58:51 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 12:58:51 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 12:58:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:58:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:58:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:58:54 INFO - Installing collected packages: psutil 12:58:54 INFO - Running setup.py install for psutil 12:58:54 INFO - building 'psutil._psutil_osx' extension 12:58:54 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 12:58:55 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 12:58:55 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 12:58:55 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 12:58:55 INFO - building 'psutil._psutil_posix' extension 12:58:55 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 12:58:55 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:58:55 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:58:55 INFO - ^ 12:58:55 INFO - 1 warning generated. 12:58:55 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 12:58:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:58:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:58:55 INFO - Successfully installed psutil 12:58:55 INFO - Cleaning up... 12:58:55 INFO - Return code: 0 12:58:55 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 12:58:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:58:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:58:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:58:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:58:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10bd15a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd25420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb55b5903c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbb4e00>, '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 0x10ba1ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbafb20>, '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.tNXkH2dPO2/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.SyAF0xPJk1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 12:58:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 12:58:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 12:58:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tNXkH2dPO2/Render', 12:58:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:58:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:58:55 INFO - 'HOME': '/Users/cltbld', 12:58:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:58:55 INFO - 'LOGNAME': 'cltbld', 12:58:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:58:55 INFO - 'MOZ_NO_REMOTE': '1', 12:58:55 INFO - 'NO_EM_RESTART': '1', 12:58:55 INFO - 'PAGER': '/bin/cat', 12:58:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:58:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:58:55 INFO - 'PWD': '/builds/slave/test', 12:58:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:58:55 INFO - 'SHELL': '/bin/bash', 12:58:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SyAF0xPJk1/Listeners', 12:58:55 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 12:58:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:58:55 INFO - 'USER': 'cltbld', 12:58:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:58:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:58:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:58:55 INFO - 'XPC_FLAGS': '0x0', 12:58:55 INFO - 'XPC_SERVICE_NAME': '0', 12:58:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:58:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:58:56 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 12:58:56 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 12:58:56 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 12:58:56 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 12:58:56 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 12:58:56 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 12:58:56 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 12:58:58 INFO - Downloading mozsystemmonitor-0.0.tar.gz 12:58:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:58:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 12:58:58 INFO - Installing collected packages: mozsystemmonitor 12:58:58 INFO - Running setup.py install for mozsystemmonitor 12:58:58 INFO - Successfully installed mozsystemmonitor 12:58:58 INFO - Cleaning up... 12:58:58 INFO - Return code: 0 12:58:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:58:58 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')]} 12:58:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:58:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58:58 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')]} 12:58:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:58:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:58:58 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 0x10bd15a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd25420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb55b5903c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbb4e00>, '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 0x10ba1ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbafb20>, '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.tNXkH2dPO2/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.SyAF0xPJk1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 12:58:58 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 12:58:58 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 12:58:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tNXkH2dPO2/Render', 12:58:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:58:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:58:58 INFO - 'HOME': '/Users/cltbld', 12:58:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:58:58 INFO - 'LOGNAME': 'cltbld', 12:58:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:58:58 INFO - 'MOZ_NO_REMOTE': '1', 12:58:58 INFO - 'NO_EM_RESTART': '1', 12:58:58 INFO - 'PAGER': '/bin/cat', 12:58:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:58:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:58:58 INFO - 'PWD': '/builds/slave/test', 12:58:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:58:58 INFO - 'SHELL': '/bin/bash', 12:58:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SyAF0xPJk1/Listeners', 12:58:58 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 12:58:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:58:58 INFO - 'USER': 'cltbld', 12:58:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:58:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:58:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:58:58 INFO - 'XPC_FLAGS': '0x0', 12:58:58 INFO - 'XPC_SERVICE_NAME': '0', 12:58:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:58:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:58:58 INFO - Downloading/unpacking blobuploader==1.2.4 12:58:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:58:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:58:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:58:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:58:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:58:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:59:01 INFO - Downloading blobuploader-1.2.4.tar.gz 12:59:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:59:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:59: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 12:59: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 12:59: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 12:59: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 12:59: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 12:59: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 12:59:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:59:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:59:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:59:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:59:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:59:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:59:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:59:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:59:02 INFO - Downloading docopt-0.6.1.tar.gz 12:59:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:59:02 INFO - Installing collected packages: blobuploader, requests, docopt 12:59:02 INFO - Running setup.py install for blobuploader 12:59:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:59:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:59:02 INFO - Running setup.py install for requests 12:59:03 INFO - Running setup.py install for docopt 12:59:03 INFO - Successfully installed blobuploader requests docopt 12:59:03 INFO - Cleaning up... 12:59:03 INFO - Return code: 0 12:59:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:59:03 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')]} 12:59:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:59:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:03 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')]} 12:59:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:59:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:03 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 0x10bd15a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd25420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb55b5903c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbb4e00>, '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 0x10ba1ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbafb20>, '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.tNXkH2dPO2/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.SyAF0xPJk1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 12:59:03 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 12:59:03 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 12:59:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tNXkH2dPO2/Render', 12:59:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:59:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:59:03 INFO - 'HOME': '/Users/cltbld', 12:59:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:59:03 INFO - 'LOGNAME': 'cltbld', 12:59:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:59:03 INFO - 'MOZ_NO_REMOTE': '1', 12:59:03 INFO - 'NO_EM_RESTART': '1', 12:59:03 INFO - 'PAGER': '/bin/cat', 12:59:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:59:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:59:03 INFO - 'PWD': '/builds/slave/test', 12:59:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:59:03 INFO - 'SHELL': '/bin/bash', 12:59:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SyAF0xPJk1/Listeners', 12:59:03 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 12:59:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:59:03 INFO - 'USER': 'cltbld', 12:59:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:59:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:59:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:59:03 INFO - 'XPC_FLAGS': '0x0', 12:59:03 INFO - 'XPC_SERVICE_NAME': '0', 12:59:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:59:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:59:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:59:03 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-FlSAgk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:59:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:59:03 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-NNBhyh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:59:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:59:04 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-rQJcXc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:59:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:59:04 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-2fnipj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:59:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:59:04 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-GpVJei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:59:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:59:04 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-O_GOzG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:59:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:59:04 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-h_MAdj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:59:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:59:04 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-fD8rJ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:59:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:59:04 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-8ulSc1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:59:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:59:04 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-BNkIT2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:59:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:59:04 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-0fSxUo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:59:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:59:04 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-K5dXDK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:59:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:59:05 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-ufkntS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:59:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:59:05 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-mqXrKi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:59:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:59:05 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-XyJCvu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:59:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:59:05 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-duQi6f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:59:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:59:05 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-mTE1ub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:59:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:59:05 INFO - Running setup.py install for manifestparser 12:59:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:59:05 INFO - Running setup.py install for mozcrash 12:59:05 INFO - Running setup.py install for mozdebug 12:59:05 INFO - Running setup.py install for mozdevice 12:59:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:59:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:59:06 INFO - Running setup.py install for mozfile 12:59:06 INFO - Running setup.py install for mozhttpd 12:59:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:59:06 INFO - Running setup.py install for mozinfo 12:59:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:59:06 INFO - Running setup.py install for mozInstall 12:59:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:59:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:59:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:59:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:59:06 INFO - Running setup.py install for mozleak 12:59:06 INFO - Running setup.py install for mozlog 12:59:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:59:07 INFO - Running setup.py install for moznetwork 12:59:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:59:07 INFO - Running setup.py install for mozprocess 12:59:07 INFO - Running setup.py install for mozprofile 12:59:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:59:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:59:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:59:07 INFO - Running setup.py install for mozrunner 12:59:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:59:07 INFO - Running setup.py install for mozscreenshot 12:59:07 INFO - Running setup.py install for moztest 12:59:07 INFO - Running setup.py install for mozversion 12:59:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:59:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:59:08 INFO - Cleaning up... 12:59:08 INFO - Return code: 0 12:59:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:59: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')]} 12:59:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:59:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59: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')]} 12:59:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:59:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:08 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 0x10bd15a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd25420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb55b5903c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbb4e00>, '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 0x10ba1ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbafb20>, '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.tNXkH2dPO2/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.SyAF0xPJk1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 12:59:08 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 12:59:08 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 12:59:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tNXkH2dPO2/Render', 12:59:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:59:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:59:08 INFO - 'HOME': '/Users/cltbld', 12:59:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:59:08 INFO - 'LOGNAME': 'cltbld', 12:59:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:59:08 INFO - 'MOZ_NO_REMOTE': '1', 12:59:08 INFO - 'NO_EM_RESTART': '1', 12:59:08 INFO - 'PAGER': '/bin/cat', 12:59:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:59:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:59:08 INFO - 'PWD': '/builds/slave/test', 12:59:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:59:08 INFO - 'SHELL': '/bin/bash', 12:59:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SyAF0xPJk1/Listeners', 12:59:08 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 12:59:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:59:08 INFO - 'USER': 'cltbld', 12:59:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:59:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:59:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:59:08 INFO - 'XPC_FLAGS': '0x0', 12:59:08 INFO - 'XPC_SERVICE_NAME': '0', 12:59:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:59:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:59:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:59:08 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-ZNAvlt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:59:08 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)) 12:59:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:59:08 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-QVFQfs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:59:08 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)) 12:59:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:59:08 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-DdVip3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:59:08 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)) 12:59:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:59:08 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-7R6Z8N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:59:08 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)) 12:59:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:59:08 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-Wlx9Pl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:59:08 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)) 12:59:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:59:08 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-Cghy_P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:59:09 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)) 12:59:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:59:09 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-jrE8QF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:59:09 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)) 12:59:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:59:09 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-NbfZA_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:59:09 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)) 12:59:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:59:09 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-_Ib8gR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:59:09 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)) 12:59:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:59:09 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-zJpc93-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:59:09 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)) 12:59:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:59:09 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-BIl1j_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:59:09 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)) 12:59:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:59:09 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-vEwc1C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:59:09 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)) 12:59:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:59:09 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-NO0uCk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:59:09 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)) 12:59:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:59:09 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-DWXrAp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:59:09 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)) 12:59:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:59:09 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-XBn6NF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:59:09 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)) 12:59:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:59:09 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-eeEqS4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:59:10 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)) 12:59:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:59:10 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-CjkN1s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:59:10 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)) 12:59:10 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)) 12:59:10 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)) 12:59:10 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)) 12:59:10 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)) 12:59:10 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)) 12:59:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:59:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:59:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:59:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:59:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:59:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:59:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:59:12 INFO - Downloading blessings-1.6.tar.gz 12:59:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:59:12 INFO - Installing collected packages: blessings 12:59:12 INFO - Running setup.py install for blessings 12:59:12 INFO - Successfully installed blessings 12:59:12 INFO - Cleaning up... 12:59:12 INFO - Return code: 0 12:59:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:59:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:59:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:59:13 INFO - Reading from file tmpfile_stdout 12:59:13 INFO - Current package versions: 12:59:13 INFO - blessings == 1.6 12:59:13 INFO - blobuploader == 1.2.4 12:59:13 INFO - docopt == 0.6.1 12:59:13 INFO - manifestparser == 1.1 12:59:13 INFO - mozInstall == 1.12 12:59:13 INFO - mozcrash == 0.17 12:59:13 INFO - mozdebug == 0.1 12:59:13 INFO - mozdevice == 0.48 12:59:13 INFO - mozfile == 1.2 12:59:13 INFO - mozhttpd == 0.7 12:59:13 INFO - mozinfo == 0.9 12:59:13 INFO - mozleak == 0.1 12:59:13 INFO - mozlog == 3.1 12:59:13 INFO - moznetwork == 0.27 12:59:13 INFO - mozprocess == 0.22 12:59:13 INFO - mozprofile == 0.28 12:59:13 INFO - mozrunner == 6.11 12:59:13 INFO - mozscreenshot == 0.1 12:59:13 INFO - mozsystemmonitor == 0.0 12:59:13 INFO - moztest == 0.7 12:59:13 INFO - mozversion == 1.4 12:59:13 INFO - psutil == 3.1.1 12:59:13 INFO - requests == 1.2.3 12:59:13 INFO - wsgiref == 0.1.2 12:59:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:59:13 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')]} 12:59:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:59:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:13 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')]} 12:59:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:59:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:13 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 0x10bd15a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd25420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb55b5903c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbb4e00>, '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 0x10ba1ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbafb20>, '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.tNXkH2dPO2/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.SyAF0xPJk1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 12:59:13 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 12:59:13 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 12:59:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tNXkH2dPO2/Render', 12:59:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:59:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:59:13 INFO - 'HOME': '/Users/cltbld', 12:59:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:59:13 INFO - 'LOGNAME': 'cltbld', 12:59:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:59:13 INFO - 'MOZ_NO_REMOTE': '1', 12:59:13 INFO - 'NO_EM_RESTART': '1', 12:59:13 INFO - 'PAGER': '/bin/cat', 12:59:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:59:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:59:13 INFO - 'PWD': '/builds/slave/test', 12:59:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:59:13 INFO - 'SHELL': '/bin/bash', 12:59:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SyAF0xPJk1/Listeners', 12:59:13 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 12:59:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:59:13 INFO - 'USER': 'cltbld', 12:59:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:59:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:59:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:59:13 INFO - 'XPC_FLAGS': '0x0', 12:59:13 INFO - 'XPC_SERVICE_NAME': '0', 12:59:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:59:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:59:13 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)) 12:59:13 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)) 12:59:13 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)) 12:59:13 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)) 12:59:13 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)) 12:59:13 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)) 12:59:13 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)) 12:59:13 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)) 12:59:13 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)) 12:59:13 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)) 12:59:13 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)) 12:59:13 INFO - Cleaning up... 12:59:13 INFO - Return code: 0 12:59:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:59:13 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')]} 12:59:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:59:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:13 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')]} 12:59:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:59:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:13 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 0x10bd15a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd25420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb55b5903c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbb4e00>, '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 0x10ba1ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbafb20>, '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.tNXkH2dPO2/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.SyAF0xPJk1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 12:59:13 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 12:59:13 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 12:59:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tNXkH2dPO2/Render', 12:59:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:59:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:59:13 INFO - 'HOME': '/Users/cltbld', 12:59:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:59:13 INFO - 'LOGNAME': 'cltbld', 12:59:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:59:13 INFO - 'MOZ_NO_REMOTE': '1', 12:59:13 INFO - 'NO_EM_RESTART': '1', 12:59:13 INFO - 'PAGER': '/bin/cat', 12:59:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:59:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:59:13 INFO - 'PWD': '/builds/slave/test', 12:59:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:59:13 INFO - 'SHELL': '/bin/bash', 12:59:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SyAF0xPJk1/Listeners', 12:59:13 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 12:59:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:59:13 INFO - 'USER': 'cltbld', 12:59:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:59:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:59:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:59:13 INFO - 'XPC_FLAGS': '0x0', 12:59:13 INFO - 'XPC_SERVICE_NAME': '0', 12:59:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:59:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:59:13 INFO - Downloading/unpacking jsonschema 12:59:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:59:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:59:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:59:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:59:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:59:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:59:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:59:16 INFO - Downloading/unpacking functools32 (from jsonschema) 12:59:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:59:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:59:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:59:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:59:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:59:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:59:16 INFO - Downloading functools32-3.2.3-2.tar.gz 12:59:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:59:16 INFO - warning: no files found matching '*.txt' 12:59:16 INFO - no previously-included directories found matching 'build' 12:59:16 INFO - no previously-included directories found matching 'dist' 12:59:16 INFO - no previously-included directories found matching '.git*' 12:59:16 INFO - Installing collected packages: jsonschema, functools32 12:59:16 INFO - Running setup.py install for functools32 12:59:16 INFO - warning: no files found matching '*.txt' 12:59:16 INFO - no previously-included directories found matching 'build' 12:59:16 INFO - no previously-included directories found matching 'dist' 12:59:16 INFO - no previously-included directories found matching '.git*' 12:59:16 INFO - Successfully installed jsonschema functools32 12:59:16 INFO - Cleaning up... 12:59:17 INFO - Return code: 0 12:59:17 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 12:59:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:59:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:59:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:59:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:59:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:59:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bd15a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd25420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb55b5903c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bbb4e00>, '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 0x10ba1ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbafb20>, '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.tNXkH2dPO2/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.SyAF0xPJk1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 12:59:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 12:59:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 12:59:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tNXkH2dPO2/Render', 12:59:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:59:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:59:17 INFO - 'HOME': '/Users/cltbld', 12:59:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:59:17 INFO - 'LOGNAME': 'cltbld', 12:59:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:59:17 INFO - 'MOZ_NO_REMOTE': '1', 12:59:17 INFO - 'NO_EM_RESTART': '1', 12:59:17 INFO - 'PAGER': '/bin/cat', 12:59:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:59:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:59:17 INFO - 'PWD': '/builds/slave/test', 12:59:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:59:17 INFO - 'SHELL': '/bin/bash', 12:59:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SyAF0xPJk1/Listeners', 12:59:17 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 12:59:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:59:17 INFO - 'USER': 'cltbld', 12:59:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:59:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:59:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:59:17 INFO - 'XPC_FLAGS': '0x0', 12:59:17 INFO - 'XPC_SERVICE_NAME': '0', 12:59:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:59:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:59:17 INFO - Downloading/unpacking flake8 12:59:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:59:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:59:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:59:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:59:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:59:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:59:19 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 12:59:19 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 12:59:19 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 12:59:19 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 12:59:19 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 12:59:19 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 12:59:19 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 12:59:19 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 12:59:20 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 12:59:20 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 12:59:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:59:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:59:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:59:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:59:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:59:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:59:20 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 12:59:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:59:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:59:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:59:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:59:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:59:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:59:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 12:59:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:59:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:59:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:59:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:59:20 INFO - no previously-included directories found matching 'docs/_build' 12:59:20 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 12:59:21 INFO - Running setup.py install for pep8 12:59:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:59:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:59:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:59:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:59:21 INFO - no previously-included directories found matching 'docs/_build' 12:59:21 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 12:59:21 INFO - Successfully installed flake8 mccabe pyflakes pep8 12:59:21 INFO - Cleaning up... 12:59:21 INFO - Return code: 0 12:59:21 INFO - Running post-action listener: _resource_record_post_action 12:59:21 INFO - Running post-action listener: _start_resource_monitoring 12:59:21 INFO - Starting resource monitoring. 12:59:21 INFO - ##### 12:59:21 INFO - ##### Running install step. 12:59:21 INFO - ##### 12:59:21 INFO - Running pre-action listener: _resource_record_pre_action 12:59:21 INFO - Running main action method: install 12:59:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:59:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:59:21 INFO - Reading from file tmpfile_stdout 12:59:21 INFO - Detecting whether we're running mozinstall >=1.0... 12:59:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:59:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:59:21 INFO - Reading from file tmpfile_stdout 12:59:21 INFO - Output received: 12:59:21 INFO - Usage: mozinstall [options] installer 12:59:21 INFO - Options: 12:59:21 INFO - -h, --help show this help message and exit 12:59:21 INFO - -d DEST, --destination=DEST 12:59:21 INFO - Directory to install application into. [default: 12:59:21 INFO - "/builds/slave/test"] 12:59:21 INFO - --app=APP Application being installed. [default: firefox] 12:59:21 INFO - mkdir: /builds/slave/test/build/application 12:59:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:59:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 12:59:52 INFO - Reading from file tmpfile_stdout 12:59:52 INFO - Output received: 12:59:52 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 12:59:52 INFO - Running post-action listener: _resource_record_post_action 12:59:52 INFO - ##### 12:59:52 INFO - ##### Running run-tests step. 12:59:52 INFO - ##### 12:59:52 INFO - Running pre-action listener: _resource_record_pre_action 12:59:52 INFO - Running main action method: run_tests 12:59:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:59:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:59:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:59:52 INFO - 2016-05-30 12:59:52.868 screenresolution[1645:7632] starting screenresolution argv=screenresolution get 12:59:52 INFO - 2016-05-30 12:59:52.870 screenresolution[1645:7632] Display 0: 1600x1200x32@60 12:59:52 INFO - 2016-05-30 12:59:52.876 screenresolution[1646:7634] starting screenresolution argv=screenresolution list 12:59:52 INFO - Available Modes on Display 0 12:59:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:59:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:59:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:59:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:59:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:59:53 INFO - Intel Iris: 12:59:53 INFO - Chipset Model: Intel Iris 12:59:53 INFO - Type: GPU 12:59:53 INFO - Bus: Built-In 12:59:53 INFO - VRAM (Dynamic, Max): 1536 MB 12:59:53 INFO - Vendor: Intel (0x8086) 12:59:53 INFO - Device ID: 0x0a2e 12:59:53 INFO - Revision ID: 0x0009 12:59:53 INFO - Displays: 12:59:53 INFO - Display: 12:59:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:59:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:59:53 INFO - Display Serial Number: 1600x1200 60 12:59:53 INFO - Main Display: Yes 12:59:53 INFO - Mirror: Off 12:59:53 INFO - Online: Yes 12:59:53 INFO - Rotation: Supported 12:59:53 INFO - Return code: 0 12:59:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:59:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:59:53 INFO - Python 2.7.3 12:59:53 INFO - Return code: 0 12:59:53 INFO - grabbing minidump binary from tooltool 12:59:53 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')]} 12:59:53 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 0x10bbb4e00>, '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 0x10ba1ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bbafb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:59:53 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 12:59:53 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 12:59:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:59:53 INFO - Return code: 0 12:59:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:59:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:59:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:59:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:59:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:59:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 12:59:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 12:59:54 INFO - Return code: 0 12:59:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0375', '--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 12:59:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0375 --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 12:59:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tNXkH2dPO2/Render', 12:59:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:59:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:59:54 INFO - 'HOME': '/Users/cltbld', 12:59:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:59:54 INFO - 'LOGNAME': 'cltbld', 12:59:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:59:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:59:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:59:54 INFO - 'MOZ_NO_REMOTE': '1', 12:59:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:59:54 INFO - 'NO_EM_RESTART': '1', 12:59:54 INFO - 'PAGER': '/bin/cat', 12:59:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:59:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:59:54 INFO - 'PWD': '/builds/slave/test', 12:59:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:59:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:59:54 INFO - 'SHELL': '/bin/bash', 12:59:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SyAF0xPJk1/Listeners', 12:59:54 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 12:59:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:59:54 INFO - 'USER': 'cltbld', 12:59:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:59:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:59:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:59:54 INFO - 'XPC_FLAGS': '0x0', 12:59:54 INFO - 'XPC_SERVICE_NAME': '0', 12:59:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:59:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0375', '--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 12:59:54 INFO - mozversion application_buildid: 20160530120549 12:59:54 INFO - mozversion application_changeset: 3c7a33fe0b6472bf47264c2390c5bc19f1a6dce4 12:59:54 INFO - mozversion application_display_name: FirefoxDeveloperEdition 12:59:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:59:54 INFO - mozversion application_name: Firefox 12:59:54 INFO - mozversion application_remotingname: firefox-dev 12:59:54 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:59:54 INFO - mozversion application_vendor: Mozilla 12:59:54 INFO - mozversion application_version: 48.0a2 12:59:54 INFO - mozversion platform_buildid: 20160530120549 12:59:54 INFO - mozversion platform_changeset: 3c7a33fe0b6472bf47264c2390c5bc19f1a6dce4 12:59:54 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:59:54 INFO - mozversion platform_version: 48.0a2 12:59:54 INFO - using testdate: 1464638394 12:59:54 INFO - actual date: 1464638394 12:59:54 INFO - starting webserver on 'localhost:49281' 12:59:54 INFO - SUITE-START | Running 2 tests 12:59:54 INFO - TEST-START | dromaeo_css 12:59:54 INFO - operating with platform_type : mac_ 12:59:54 INFO - Initialising browser for dromaeo_css test... 12:59:54 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/tmpc1rshX/profile http://localhost:49281/getInfo.html) 12:59:56 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 12:59:56 INFO - PROCESS | 1664 | colorDepth:24 12:59:56 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 12:59:56 INFO - PROCESS | 1664 | __metrics 12:59:56 INFO - PROCESS | 1664 | 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" 12:59:56 ERROR - PROCESS | 1664 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 12:59:57 INFO - TEST-INFO | 1664: exit 0 12:59:57 INFO - Browser initialized. 12:59:57 INFO - Running cycle 1/1 for dromaeo_css test... 12:59:57 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/tmpc1rshX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:00:09 INFO - PROCESS | 1666 | RSS: Main: 211427328 13:00:09 INFO - PROCESS | 1666 | 13:01:01 INFO - PROCESS | 1666 | 1464638461101 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 13:03:11 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49281/tests/dromaeo/cssquery-ext.html) 13:03:13 INFO - PROCESS | 1666 | RSS: Main: 378585088 13:03:13 INFO - PROCESS | 1666 | 13:06:16 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-ext.html (next: http://localhost:49281/tests/dromaeo/cssquery-jquery.html) 13:06:16 INFO - PROCESS | 1666 | RSS: Main: 354136064 13:06:16 INFO - PROCESS | 1666 | 13:09:18 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49281/tests/dromaeo/cssquery-mootools.html) 13:09:19 INFO - PROCESS | 1666 | RSS: Main: 360366080 13:09:19 INFO - PROCESS | 1666 | 13:12:21 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49281/tests/dromaeo/cssquery-prototype.html) 13:12:22 INFO - PROCESS | 1666 | RSS: Main: 357031936 13:12:22 INFO - PROCESS | 1666 | 13:15:25 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49281/tests/dromaeo/cssquery-yui.html) 13:15:25 INFO - PROCESS | 1666 | RSS: Main: 326246400 13:15:25 INFO - PROCESS | 1666 | 13:18:28 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-yui.html (next: http://localhost:49281/tests/dromaeo/cssquery-dojo.html) 13:18:28 INFO - PROCESS | 1666 | RSS: Main: 370487296 13:18:28 INFO - PROCESS | 1666 | 13:18:28 INFO - PROCESS | 1666 | __start_tp_report 13:18:28 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 13:18:28 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 13:18:28 INFO - PROCESS | 1666 | |i|pagename|runs| 13:18:28 INFO - PROCESS | 1666 | |0;dojo.html;3378.020374091513;3429.6398878117798;3447.3967055765233;3469.2020835207886;3512.8946131616062;5639.802606908754;5659.490645841874;5661.688607126612;5680.261565996425;5682.26130603021;5652.208690783294;5700.7719691212405;5702.600817942745;5702.942970570293;5731.512821410175;5095.770690318945;5116.641835071556;5160.974195129038;5244.842654720364;5253.238280449333;5227.817026404076;5230.215467679859;5239.083160446926;5245;5249.028929648028;5132.332796736419;5169.974150129263;5184.326037615105;5193.428722840485;5238.659487133325;5197.584193264549;5257.316548848626;5258.474152584749;5261.5790736741355;5306.70813105279;5041.1682072458;5079.822206222764;5081.847544573632;5161.380634323905;5166.715830629314;5491.917621235684;5546.55627549795;5556.86107847303;5565.554755619541;5574.9721251393885;6164.229471316085;6208.037754148069;6227.221597300338;6230.595011324294;6236.282827474808;4739.549742774431;6191.288001879797;6263.937360626381;6289.308176100625;6289.433950944437;5823.068309070529;5836.766529338822;5841.970790146022;5847.1229315603;5848.2689663792025;6260.217472815898;6263.592866463665;6271.717772700239;6274.843128921722;6281.654509002049;5961.523078153824;6025.739282763823;6055.666938318434;6073.726682299307;6160.9383906160365;6122.785702500391;6136.978641397228;6138.756267604181;6157.7229024694;6168.537359698041;6145.678679083929;6219.906701399483;6220.657863817533;6246.281677607042;6256.217972753406;6173.290071641819;6207.441330280206;6209.223847019122;6230.190075290274;6245.094461303085;4837.830675926396;4906.656534042583;4929.014197160511;4932.778024988884;4950.950490495121;4354.651627869763;4386.990992071841;4389.078293558388;4389.956100439019;4438.245498265303;5664.150377443417;5682.707209025336;5693.316801983706;5698.202251684767;5755.079187330007;5996.280446346379;6031.185789918392;6186.19579454677;6241.157443745036;6273.215848018997;5580.023495888203;5589.413111623221;5592.748326325243;5604.915926261109;5605.355384130796;5414.83755487336;5437.673739575638;5443.292371991616;5468.316460442445;5490.176473529003;6336.778212762623;6354.777582784581;6356.300806911236;6361.777337793247;6365.522585806083;6121.785737499166;6191.690415479118;6246.3753624637175;6250.749970001331;6262.874742505215;6280.183576135073;6300.180976473029;6327.335629758809;6328.683565821599;6338.809835704937;6171.660558669317;6176.4441200292185;6179.907301390393;6186.969065154645;6205.6586887721605;6004.129401236709;6213.2233470816145;6258.812235632939;6266.811995640138;6279.811605651837;5849.853753656193;5986.401359864153;6054.273487181566;6061.030235162353;6066.332703402711;6036.3661815510495;6062.757489700362;6069.696515174312;6073.848153796013;6080.756769729338;5635.88728225425;5643.984082865124;5773.8556536086935;5905.557083218861;5940.257467816523;5879.617824841372;5890.7349169288345;5927.436893495111;5967.76128954837;5975.282966043928;5003.949960500348;5797.304323481209;5824.563157763269;5832.212651292021;5846.649201047951 13:18:28 INFO - PROCESS | 1666 | |1;ext.html;517.2913109040612;522.9697495933012;547.7891011960394;548.9313835770529;599.2090440618385;30428.84785576072;31032.379352412965;37203.99999999996;38465.80767096161;38478.845634631005;40257.59742402582;40721.796391018004;40741.77774666765;40796;40896;9351.298652601065;9552.904470955305;9571.473568953705;9587.90412095877;9592.952035239814;8322;8546.359023073295;10744.14046876252;11620;11670.47482863269;11373.260738052;11449.885501145007;11451.96932276095;11474;11575.958163765259;8545.786355341104;11285.379304138269;11370.71573210668;11376.943115284454;11579.652610421701;11422.942885285604;11551.191416600841;11564.479598418091;11572.710682232928;11575.305481671126;38034.42948355777;38390;38478.615213847785;38520.80739596284;38534.03664908372;3153.984230078835;3193.872245110216;3365.175531994677;3760.830762615689;3809.3904975204114;27471.587926181124;27922.581161282596;28110;28303;28449.857750711115;2731.6039174319612;2749.766269867064;2765.861706914646;2804.803663743539;2819.7321254481;441040.794796024;445843;447401;448498;449207.7539612314;62699.6865015672;63001.68499157475;63169.05246421308;63416.68291658512;63444.68277658674;68733.3126668737;70179.29820701727;70217.64891175511;70258;70279;17483.213255403283;17687.911560442117;17710.29158833658;17728.84762490434;17810.821891781176;12497.687557810945;12747.171433856769;12759.93620031894;13371.933140334237;13503.662408439868;17156.22796974139;17384.4784656458;19548.36938892626;20024.898630575168;20233.49416264576;8003.399320135996;8115.75652730419;8149.492343916394;8194.54929978845;8211.630476628567;946.1153821177221;975.6975337645353;1001.7495626093477;1056.0970370333407;1126.8197088465806;28480.715192847805;28500.42999140047;28515.714842851306;28999.42001159965;29064.273393164916;932.8134373125403;1096.31480324797;1101.8656476306805;1140.560884059643;1146.4726225935997;12851.807222891664;15616.531504054896;15763.763543546856;16019.359225630844;16403.6719265614;583785;586112;589089;589415;593185.0340748269;60623.09065364023;60985.69507152436;61012.69493652592;61113;61161.69419152965;1399.8740113389845;1492.8283247426684;1517.7723341498863;1526.137732181314;1534.823495298055;27954.86022569874;28461.857690711415;28705.28236794097;29195.270118247212;29379.412411752073;51291.974160515834;51440.22839657483;52226;52442.95114097773;52463;505815;508906.09922554315;509874;510025;510914;23715.64426533569;26776.732232677423;26856;27081.59377609298;27342.863285683445;39873;41227.58772412237;41511.7924410376;41936.58063419327;42203.1559368817;34070.737321485634;34583;36290.63709362873;36866.44700329444;37041.44437833488;35570.46644300286;35790;35981;36569.45145822762;36771;35913.64086359103;36198.914032579065;36199.45700814542;36356.09109772277;36602.26795464129;31300.843495782374;31891.202719932186;31927;31954.84022579872;32065.519017214294;23875.16436924699;24361.634575481028;24364.531678052896;24542.877285613456;24748.886300116897 13:18:28 INFO - PROCESS | 1666 | |2;jquery.html;369.27437585145185;405.4465654381769;408.4955080475613;408.8446390371656;421.17870153201244;18624.72062919054;19842.60314793705;20445.89777051113;20449.386518404433;20483.487912802186;19911.601767964632;20122.49693757657;20206.696899546518;21571;21577.568448631017;5310.415854256048;5677.233573467571;5911.231539899818;5999.880002399971;6122.8469288267925;5477.917831232535;5967.254093238345;5988.880222395549;6014.278286605613;6036;5688.800891968782;5755.740991655385;6024.126501657257;6074.787382441616;6086.634801911878;6000.159977603139;6020.96989515054;6036.758529658852;6046.758129674808;6051.636901785885;5436.320459942507;5740.9712951435395;5754.050581654022;5830;5848.41515848416;12996.675083122904;13439.865601344058;13563.525276615272;13630.591082267549;13671;1726.9481915542553;2228.1310288987306;2303.9045013678187;2375.4298968247667;2819.6616406031203;9666.130048295687;10111.342762720473;10167.491625418774;12166.026717862549;14920.701585968329;1607.7427611582207;1760.8679349048743;1763.6208215233657;1769.4868488138425;1775.5649865782837;96947;115436;115635.42182289119;118873.81126188714;119443;9790.461524616214;10249.9487502562;11182.713987891333;11865.525378984916;11992.580259690869;15274.31265593051;15298.770518442232;15299.923500382427;15306.234688265542;15338.923305383401;8897.955510222537;9088.909110908806;9095.954520227357;9182.265418766483;9188.90811091894;9095.181433671001;9116.680916168036;9128.406653567497;9221;9234.630614775468;4095.569965153616;4419.784559246169;4741.099191153669;4754.857354279377;4782.736949467812;1937.3413039566615;1970.787965401268;1989.532459871929;1999.7700264469772;2035.8574899756875;895.3911340288536;980.8724865767405;1005.9295849290479;1005.9899401005896;1031.3450958641317;20091.39725808228;20371.592568148553;20489.97550122488;20503.897480512504;20557;966.917811985975;970.8689326940914;989.4805227255748;992.0426788149326;1001.7127990532131;9278.932922713839;10328.690139295833;10542.683719488274;10623.990720881504;10698.358098514114;584395;603880;609822;619866;622861;112393;112500.43749781199;114788.42605786918;115168;118768;1015.8323876560432;1027.1525991057258;1034.281174583657;1052.752603138262;1055.9313644613076;17350.132493375535;17372.044537550555;17575.912120439316;17639.470815875033;18706.532336691693;49438.011239775726;50328;50529.32389426547;51936.48063519316;51949.480505194464;64048.67975660092;67502.46091271004;68061;68120.65939670468;68622;8367.414281000272;8367.707130250656;8478.57607119654;8485.830283394174;8768.25469835071;12122.575709850113;12672.809907851206;12895.742085158057;13128.212307261594;13164.80252796228;11129.833052504056;11307.943460282646;11396.08831293478;11471;11499;10938.343699378063;10980;10997.890021100007;11210.495527701436;11430.885691142983;11338.489767960296;11411.201215914807;11584.131190160602;11740.41297935117;11746.588869389872;8372.618517944715;8382.371322150984;8384.245417912172;8448.662053518035;8470.618822152896;10704.625338113128;10875.456227188768;11431;11479.598214062467;11538 13:18:28 INFO - PROCESS | 1666 | |3;mootools.html;1414.0243232169796;1575.1572908493954;1599.2243761775537;1643.9314445610353;1986.0566231040257;7318;7810;8042.4772389794625;8683.956580217106;8685.218330350268;8444.831103377945;8500.107488713673;8588.184122508352;8617.009043959946;8636.13638636136;2179.4769255378756;2423.248792874215;2440.2679196241056;2445.9143929962456;2449.0448724997264;2386.7016622922133;2418.613021916494;2433.221369161869;2436.8050555955483;2436.9512609747794;2375.7743014413604;2414.770596793302;2420.044082587377;2420.4674971506242;2423.212455951814;2366.4438856868624;2406.0856874387796;2412.0231306320966;2415.033986405443;2426.296374051523;2360.7285162206394;2410.421498840283;2414.010255795133;2414.2274472168915;2417.033186725324;8703.95648021756;8764.824703505958;8772.692955746517;8773.473591584461;8789.516576588285;4471.910561788746;5430.59270554706;5458.6724796511935;5474.562035037227;5479.698616576086;3384.069380920242;3389.7288216942584;3391.5930088389305;3440.9827950860335;3445.776024558421;1037.7457522906861;1045.2108657963265;1049.5539395756773;1057.3285963413289;1062.6918193723811;11708.88291117095;11802.46888890002;11805;11816.409179540988;11827.64517064498;4552.453705555288;4760.809567617326;4889.315495830587;4890.070886531547;4907.1657818171;9133.178013978773;9162.633494660273;9229.261659067259;9246.4914429707;9246.768830779285;5269.446708095671;5291.259223708684;5299.33758280215;5306.310179676694;5314.548263397654;4138.337865941495;4268.637165840876;4381.430414046154;4428.1365133799245;4915.754212289371;3779.4519794629623;3858.151206734514;3860.652541271299;3884.76691398517;3887.3391523441082;1168.7195073182374;1184.691980085184;1195.235049568277;1199.682084247675;1202.2606097250127;843.9324854011787;873.9257163141077;905.0270958719403;907.2197909797569;924.6578765856676;5791.420857914259;5867.178594996704;5970.671613061237;6005.789797357072;6132.08018797184;462.4774005373906;462.9444466664022;465.50888812303344;471.62505807882815;481.87712133405756;3907.29668659644;3964.801759911993;3968.0675041365257;4006.2187873364414;4056.6146216110014;10318.122959548522;10628.202884783672;10711.94644026775;10730.034296913314;10755.462226888625;10452.790944181073;10609.57561697539;10616.628418005334;10707.785844283071;10972;7829.921700783033;8243.340532757366;8319.001719793663;8323.791905202419;8341;5729.742161602821;5835.766569337179;5880.50015748649;5933.940660593339;5958.374370691015;9211.631534738535;9303.116203960613;9337;9412.623495060396;9444.244460443011;9297.488638124969;9435.433873967584;9438.292128090558;9543.856842147512;9621.037896210322;5824.592278540631;6547.672616369067;6697.933020669926;7347.632618368953;7440.813979650552;7051.294870512907;8225.136360682041;8280.420370574002;8295.668173273001;8303.916960830556;6246;7301.306375894282;7362.705491780269;7384.446166537424;7531.510451820614;7241.637918104179;7257.201707812031;7306.561606303639;7323.304286092813;7339.302766237199;6839.179298484214;6924.619145947021;6953.617551034742;6957.1651401832105;6957.37383635455;4714.622830173646;4727.645426592951;4750.501197374226;4848.806047758191;4868.2940973557925;4152.626263636287;4761.142994261067;4846.176150054429;4959.479254678206;5013.448520662797 13:18:28 INFO - PROCESS | 1666 | |4;prototype.html;326.7647293948357;329.17377382769257;335.3762002675025;338.94237979543476;341.3003343146549;6496.5777224480435;6500.219973603168;6501.414872661459;6557.311482294362;6559.639219842907;6447.54867159299;6464.579802312846;6490.707918143683;6503.414692677658;6525.673716314189;4564.634829213664;4679.60223381013;4688.390509233812;4711.90576188476;4824.831130910419;4307.461567304087;4689.92131809684;4773.379460670126;4780.784864681098;4802.375691160144;4635.096156249533;4732.763361831911;4734.124187025412;4740.431148262213;4752.002079563295;4546.386237857913;4599.586037256663;4713.245880659112;4744.027474367746;4797.18447863864;4057.1074363640246;4069.796510174509;4073.388991651246;4075.1442197138635;4082.9183416331803;5374.596905232123;5684.403137670526;6088.482478989291;6196.442320191204;6197.597156184878;5097.362829646294;5646.57650676201;5662.433756624346;5665.801696940587;5667.433256674341;6976;6991.161060672701;6998.720051197946;7004.649767511604;7009.82475438113;4369.344598310247;4536.319552067216;4579.610733087691;4611.930821037688;4627.167109920213;7310;7324.377427918634;7355.117385913671;7355.9701641770735;7366.226546212624;4589.770511474413;4595.2188128018315;4596;4605.539446055368;4612.100640375094;4654.325122857167;4660.13787449323;4664.766761661904;4684.882877928011;4692.671512994058;5520.282363292747;5553.333599968029;5555.2778138841695;5564.33228012641;5572.721363931787;5701.71491425427;6873.862522749617;7021.22766495685;7022.367986881205;7041;1600.039976014382;1617.8139513956046;1629.5600187949187;1633.9591510212101;1638;1017.4048181813574;1018.4449475036123;1038.8182068138046;1042.8592140060994;1043.4313299251774;5618.971905140448;5999;6269.310375858652;6506;6725.798226053226;7411.184769675331;7414.814629634302;7418.480706350504;7428.442866784904;7433.331000209899;6651.700673469705;6665.433438157714;6774.220964589135;6813.897791533131;6823.044773731682;3842.058695619591;3891.494105766232;3894.766314021168;3896.980515097463;3898.2203559288255;7431.145418276859;7755.3407960324;7764.184760600168;7788.922110778933;7827.569483678452;6873.381395674313;7573.507721998052;7595.3164215220895;7623.656935437919;7634.580098094658;7689.461737678309;7724.575148366894;7729.768106956801;7730.884036739453;7735.497192682457;7060.3645671887725;7336.523125996793;7342.70629174827;7349.375303099297;7368.521046131984;5962.344142144274;7148.67830947619;7194.496385252982;7216.7834964951135;7219.0976127984;7725.99562056929;7753.418493612889;7781.455298129076;7783.338416234684;7788.299053085249;4581.198290299251;4636.768161591839;4638.814447422065;4640.419947506562;4641.535846415331;7052.400545953446;7301.744438944612;7315.707371705073;7326.194118647051;7333.816654583678;7066.293370662893;7116.608586527791;7119.181294151032;7121.857562848818;7140.821479463055;6100.420460056288;7055.400290975119;7077.36303732646;7106.751263705745;7109.28907109285;5666.886662266649;6906.447484201352;6917.446604271747;6920.134983127109;6927.099477067949;5967.37342579023;6655.500837437308;7079.787606371817;7093.680784364613;7102.218755936739;5370.543503802221;6406.750683616837;6625.966870165618;6930.930690693029;6931.75738849158 13:18:28 INFO - PROCESS | 1666 | |5;yui.html;696.1854630082803;741.6588369350098;757.981050473738;761.6039659377127;800.4516905919444;12263.570775022876;12979.80530292044;14606.758425533815;15054.623634409145;15077.321520531576;13219.669508262274;13572.796408053888;13612.659683507918;13663.795043074362;13689.178649281026;742.5841528743894;755.4221020919001;924.9501815439457;941.0401390581603;948.0945696859483;744.4342299852125;752.9721929566131;755.2636179724752;927.2952556057411;948.7059011706393;979.3242662562809;983.7540614846289;985.7042887133867;986.758244230161;988.6440881282732;977.3402953006728;979.8726165598426;980.2794945714894;980.9460479673616;982.5578489679637;554.334798242107;577.6534079552235;584.0713265907228;727.2509315405089;740.4372676765643;14804.629884252872;14885.851141488554;14900.180490073042;14903.956723029392;14929.626759331104;12128.878711212863;12152.756944861054;12153.331566763825;12425;12698.555550555779;10071.69784906454;10109.39343639384;10191.23565732573;10357.378557286587;10377.169826413945;2996.8501574921165;3397.286569820341;3477.600075991268;3490.458978858281;3524.6122926478065;83424.58287708522;84198.57900710519;84211.15788842094;84344.15655843486;84457.3108537832;8927;9099.681511147079;9372.5313734313;9405;9424.528773561295;20663.690044649342;20667.06998185085;20704.964751762654;20716.689249661267;20735.792642073688;7331.9266807332315;7371.594562299125;7473.85052298951;7494.925050749532;7761.0298712660915;4068;4092.3043082676018;4154.480689913761;4190.224808410393;4338.978305108454;3572.821358932095;3631.5642122945414;3634.400323946572;3641.817909104534;3642.581103173116;661.110805965975;665.7769647168253;670.5339788846704;683.460066547422;693.7225109956058;572.5276646766434;611.538288592119;616.2881871438495;625.4652272307112;630.2311180360045;7807.960960195162;8031.518108913484;8087.716929907425;8135.7152499662225;8169.489841537903;512.0578136229282;534.5082524077858;540.0414264680225;584.739790793093;616.7070641445367;3669.101070237809;3792.3173828711097;3922.1371298314325;3933.940990885139;3949.0917089069676;69338.65330673315;89251.10748892558;89460.21079578371;90666.09333906576;90694.54652726694;72405.27594724091;73673;74564;75241.62379188069;76614.61692691501;67347.66326168436;68770.65614671995;69077.3092269081;69590.60818783595;70100.64949675219;7222.205557388788;7235.782926512213;7270.600116993616;7470.887936680845;7564.357029652321;47526.76236618795;49010;49195;49197.50802491929;49356;62132;64829.351706484216;66407;66862;70081.64959175172;9828.705138845846;9842.360246583949;10004.149647279772;10104.04011618895;10143.239257055602;10543.736406589896;10858.728531786768;10980.286281391684;11009.61466348674;11526.654200374001;10255.589776409159;10267.640632578123;10295.176385889261;10392.22058345612;10404.219683523615;10199.847002294822;10378.808605894788;10666.73333166646;11725;11992.400379980792;11171.66485005451;11359.034482068786;11406;11551.711207219887;12116.970057545208;8502.489850608983;9391.953040235028;9431.528423578931;9677.274204434554;9708.466034368177;8454.323654107533;8695.695650652196;8804.459674116097;8824.073472285318;8825.867611985954 13:18:28 INFO - PROCESS | 1666 | __end_tp_report 13:18:28 INFO - PROCESS | 1666 | __start_cc_report 13:18:28 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,1607 13:18:28 INFO - PROCESS | 1666 | __end_cc_report 13:18:28 INFO - PROCESS | 1666 | __startTimestamp1464639508705__endTimestamp 13:18:28 INFO - PROCESS | 1666 | 13:18:28 INFO - PROCESS | 1666 | ------- Summary: start ------- 13:18:28 INFO - PROCESS | 1666 | Number of tests: 6 13:18:28 INFO - PROCESS | 1666 | 13:18:28 INFO - PROCESS | 1666 | [#0] dojo.html Cycles:165 Average:5700.87 Median:5847.12 stddev:628.06 (10.7%) stddev-sans-first:602.97 13:18:28 INFO - PROCESS | 1666 | Values: 3378.0 3429.6 3447.4 3469.2 3512.9 5639.8 5659.5 5661.7 5680.3 5682.3 5652.2 5700.8 5702.6 5702.9 5731.5 5095.8 5116.6 5161.0 5244.8 5253.2 5227.8 5230.2 5239.1 5245.0 5249.0 5132.3 5170.0 5184.3 5193.4 5238.7 5197.6 5257.3 5258.5 5261.6 5306.7 5041.2 5079.8 5081.8 5161.4 5166.7 5491.9 5546.6 5556.9 5565.6 5575.0 6164.2 6208.0 6227.2 6230.6 6236.3 4739.5 6191.3 6263.9 6289.3 6289.4 5823.1 5836.8 5842.0 5847.1 5848.3 6260.2 6263.6 6271.7 6274.8 6281.7 5961.5 6025.7 6055.7 6073.7 6160.9 6122.8 6137.0 6138.8 6157.7 6168.5 6145.7 6219.9 6220.7 6246.3 6256.2 6173.3 6207.4 6209.2 6230.2 6245.1 4837.8 4906.7 4929.0 4932.8 4951.0 4354.7 4387.0 4389.1 4390.0 4438.2 5664.2 5682.7 5693.3 5698.2 5755.1 5996.3 6031.2 6186.2 6241.2 6273.2 5580.0 5589.4 5592.7 5604.9 5605.4 5414.8 5437.7 5443.3 5468.3 5490.2 6336.8 6354.8 6356.3 6361.8 6365.5 6121.8 6191.7 6246.4 6250.7 6262.9 6280.2 6300.2 6327.3 6328.7 6338.8 6171.7 6176.4 6179.9 6187.0 6205.7 6004.1 6213.2 6258.8 6266.8 6279.8 5849.9 5986.4 6054.3 6061.0 6066.3 6036.4 6062.8 6069.7 6073.8 6080.8 5635.9 5644.0 5773.9 5905.6 5940.3 5879.6 5890.7 5927.4 5967.8 5975.3 5003.9 5797.3 5824.6 5832.2 5846.6 13:18:28 INFO - PROCESS | 1666 | 13:18:28 INFO - PROCESS | 1666 | [#1] ext.html Cycles:180 Average:65515.13 Median:35076.73 stddev:138007.93 (393.4%) stddev-sans-first:138308.79 13:18:28 INFO - PROCESS | 1666 | Values: 517.3 523.0 547.8 548.9 599.2 30428.8 31032.4 37204.0 38465.8 38478.8 40257.6 40721.8 40741.8 40796.0 40896.0 9351.3 9552.9 9571.5 9587.9 9593.0 8322.0 8546.4 10744.1 11620.0 11670.5 11373.3 11449.9 11452.0 11474.0 11576.0 8545.8 11285.4 11370.7 11376.9 11579.7 11422.9 11551.2 11564.5 11572.7 11575.3 38034.4 38390.0 38478.6 38520.8 38534.0 3154.0 3193.9 3365.2 3760.8 3809.4 27471.6 27922.6 28110.0 28303.0 28449.9 2731.6 2749.8 2765.9 2804.8 2819.7 441040.8 445843.0 447401.0 448498.0 449207.8 62699.7 63001.7 63169.1 63416.7 63444.7 68733.3 70179.3 70217.6 70258.0 70279.0 17483.2 17687.9 17710.3 17728.8 17810.8 12497.7 12747.2 12759.9 13371.9 13503.7 17156.2 17384.5 19548.4 20024.9 20233.5 8003.4 8115.8 8149.5 8194.5 8211.6 946.1 975.7 1001.7 1056.1 1126.8 28480.7 28500.4 28515.7 28999.4 29064.3 932.8 1096.3 1101.9 1140.6 1146.5 12851.8 15616.5 15763.8 16019.4 16403.7 583785.0 586112.0 589089.0 589415.0 593185.0 60623.1 60985.7 61012.7 61113.0 61161.7 1399.9 1492.8 1517.8 1526.1 1534.8 27954.9 28461.9 28705.3 29195.3 29379.4 51292.0 51440.2 52226.0 52443.0 52463.0 505815.0 508906.1 509874.0 510025.0 510914.0 23715.6 26776.7 26856.0 27081.6 27342.9 39873.0 41227.6 41511.8 41936.6 42203.2 34070.7 34583.0 36290.6 36866.4 37041.4 35570.5 35790.0 35981.0 36569.5 36771.0 35913.6 36198.9 36199.5 36356.1 36602.3 31300.8 31891.2 31927.0 31954.8 32065.5 23875.2 24361.6 24364.5 24542.9 24748.9 13:18:28 INFO - PROCESS | 1666 | 13:18:28 INFO - PROCESS | 1666 | [#2] jquery.html Cycles:180 Average:34196.58 Median:20486.73 stddev:100926.32 (492.6%) stddev-sans-first:101177.49 13:18:28 INFO - PROCESS | 1666 | Values: 369.3 405.4 408.5 408.8 421.2 18624.7 19842.6 20445.9 20449.4 20483.5 19911.6 20122.5 20206.7 21571.0 21577.6 5310.4 5677.2 5911.2 5999.9 6122.8 5477.9 5967.3 5988.9 6014.3 6036.0 5688.8 5755.7 6024.1 6074.8 6086.6 6000.2 6021.0 6036.8 6046.8 6051.6 5436.3 5741.0 5754.1 5830.0 5848.4 12996.7 13439.9 13563.5 13630.6 13671.0 1726.9 2228.1 2303.9 2375.4 2819.7 9666.1 10111.3 10167.5 12166.0 14920.7 1607.7 1760.9 1763.6 1769.5 1775.6 96947.0 115436.0 115635.4 118873.8 119443.0 9790.5 10249.9 11182.7 11865.5 11992.6 15274.3 15298.8 15299.9 15306.2 15338.9 8898.0 9088.9 9096.0 9182.3 9188.9 9095.2 9116.7 9128.4 9221.0 9234.6 4095.6 4419.8 4741.1 4754.9 4782.7 1937.3 1970.8 1989.5 1999.8 2035.9 895.4 980.9 1005.9 1006.0 1031.3 20091.4 20371.6 20490.0 20503.9 20557.0 966.9 970.9 989.5 992.0 1001.7 9278.9 10328.7 10542.7 10624.0 10698.4 584395.0 603880.0 609822.0 619866.0 622861.0 112393.0 112500.4 114788.4 115168.0 118768.0 1015.8 1027.2 1034.3 1052.8 1055.9 17350.1 17372.0 17575.9 17639.5 18706.5 49438.0 50328.0 50529.3 51936.5 51949.5 64048.7 67502.5 68061.0 68120.7 68622.0 8367.4 8367.7 8478.6 8485.8 8768.3 12122.6 12672.8 12895.7 13128.2 13164.8 11129.8 11307.9 11396.1 11471.0 11499.0 10938.3 10980.0 10997.9 11210.5 11430.9 11338.5 11411.2 11584.1 11740.4 11746.6 8372.6 8382.4 8384.2 8448.7 8470.6 10704.6 10875.5 11431.0 11479.6 11538.0 13:18:28 INFO - PROCESS | 1666 | 13:18:28 INFO - PROCESS | 1666 | [#3] mootools.html Cycles:180 Average:5571.80 Median:4889.69 stddev:3120.91 (63.8%) stddev-sans-first:3114.02 13:18:28 INFO - PROCESS | 1666 | Values: 1414.0 1575.2 1599.2 1643.9 1986.1 7318.0 7810.0 8042.5 8684.0 8685.2 8444.8 8500.1 8588.2 8617.0 8636.1 2179.5 2423.2 2440.3 2445.9 2449.0 2386.7 2418.6 2433.2 2436.8 2437.0 2375.8 2414.8 2420.0 2420.5 2423.2 2366.4 2406.1 2412.0 2415.0 2426.3 2360.7 2410.4 2414.0 2414.2 2417.0 8704.0 8764.8 8772.7 8773.5 8789.5 4471.9 5430.6 5458.7 5474.6 5479.7 3384.1 3389.7 3391.6 3441.0 3445.8 1037.7 1045.2 1049.6 1057.3 1062.7 11708.9 11802.5 11805.0 11816.4 11827.6 4552.5 4760.8 4889.3 4890.1 4907.2 9133.2 9162.6 9229.3 9246.5 9246.8 5269.4 5291.3 5299.3 5306.3 5314.5 4138.3 4268.6 4381.4 4428.1 4915.8 3779.5 3858.2 3860.7 3884.8 3887.3 1168.7 1184.7 1195.2 1199.7 1202.3 843.9 873.9 905.0 907.2 924.7 5791.4 5867.2 5970.7 6005.8 6132.1 462.5 462.9 465.5 471.6 481.9 3907.3 3964.8 3968.1 4006.2 4056.6 10318.1 10628.2 10711.9 10730.0 10755.5 10452.8 10609.6 10616.6 10707.8 10972.0 7829.9 8243.3 8319.0 8323.8 8341.0 5729.7 5835.8 5880.5 5933.9 5958.4 9211.6 9303.1 9337.0 9412.6 9444.2 9297.5 9435.4 9438.3 9543.9 9621.0 5824.6 6547.7 6697.9 7347.6 7440.8 7051.3 8225.1 8280.4 8295.7 8303.9 6246.0 7301.3 7362.7 7384.4 7531.5 7241.6 7257.2 7306.6 7323.3 7339.3 6839.2 6924.6 6953.6 6957.2 6957.4 4714.6 4727.6 4750.5 4848.8 4868.3 4152.6 4761.1 4846.2 4959.5 5013.4 13:18:28 INFO - PROCESS | 1666 | 13:18:28 INFO - PROCESS | 1666 | [#4] prototype.html Cycles:180 Average:5714.03 Median:6456.06 stddev:1855.16 (28.7%) stddev-sans-first:1815.76 13:18:28 INFO - PROCESS | 1666 | Values: 326.8 329.2 335.4 338.9 341.3 6496.6 6500.2 6501.4 6557.3 6559.6 6447.5 6464.6 6490.7 6503.4 6525.7 4564.6 4679.6 4688.4 4711.9 4824.8 4307.5 4689.9 4773.4 4780.8 4802.4 4635.1 4732.8 4734.1 4740.4 4752.0 4546.4 4599.6 4713.2 4744.0 4797.2 4057.1 4069.8 4073.4 4075.1 4082.9 5374.6 5684.4 6088.5 6196.4 6197.6 5097.4 5646.6 5662.4 5665.8 5667.4 6976.0 6991.2 6998.7 7004.6 7009.8 4369.3 4536.3 4579.6 4611.9 4627.2 7310.0 7324.4 7355.1 7356.0 7366.2 4589.8 4595.2 4596.0 4605.5 4612.1 4654.3 4660.1 4664.8 4684.9 4692.7 5520.3 5553.3 5555.3 5564.3 5572.7 5701.7 6873.9 7021.2 7022.4 7041.0 1600.0 1617.8 1629.6 1634.0 1638.0 1017.4 1018.4 1038.8 1042.9 1043.4 5619.0 5999.0 6269.3 6506.0 6725.8 7411.2 7414.8 7418.5 7428.4 7433.3 6651.7 6665.4 6774.2 6813.9 6823.0 3842.1 3891.5 3894.8 3897.0 3898.2 7431.1 7755.3 7764.2 7788.9 7827.6 6873.4 7573.5 7595.3 7623.7 7634.6 7689.5 7724.6 7729.8 7730.9 7735.5 7060.4 7336.5 7342.7 7349.4 7368.5 5962.3 7148.7 7194.5 7216.8 7219.1 7726.0 7753.4 7781.5 7783.3 7788.3 4581.2 4636.8 4638.8 4640.4 4641.5 7052.4 7301.7 7315.7 7326.2 7333.8 7066.3 7116.6 7119.2 7121.9 7140.8 6100.4 7055.4 7077.4 7106.8 7109.3 5666.9 6906.4 6917.4 6920.1 6927.1 5967.4 6655.5 7079.8 7093.7 7102.2 5370.5 6406.8 6626.0 6930.9 6931.8 13:18:28 INFO - PROCESS | 1666 | 13:18:28 INFO - PROCESS | 1666 | [#5] yui.html Cycles:180 Average:17777.18 Median:627.85 stddev:25108.14 (3999.1%) stddev-sans-first:25145.82 13:18:28 INFO - PROCESS | 1666 | Values: 696.2 741.7 758.0 761.6 800.5 12263.6 12979.8 14606.8 15054.6 15077.3 13219.7 13572.8 13612.7 13663.8 13689.2 742.6 755.4 925.0 941.0 948.1 744.4 753.0 755.3 927.3 948.7 979.3 983.8 985.7 986.8 988.6 977.3 979.9 980.3 980.9 982.6 554.3 577.7 584.1 727.3 740.4 14804.6 14885.9 14900.2 14904.0 14929.6 12128.9 12152.8 12153.3 12425.0 12698.6 10071.7 10109.4 10191.2 10357.4 10377.2 2996.9 3397.3 3477.6 3490.5 3524.6 83424.6 84198.6 84211.2 84344.2 84457.3 8927.0 9099.7 9372.5 9405.0 9424.5 20663.7 20667.1 20705.0 20716.7 20735.8 7331.9 7371.6 7473.9 7494.9 7761.0 4068.0 4092.3 4154.5 4190.2 4339.0 3572.8 3631.6 3634.4 3641.8 3642.6 661.1 665.8 670.5 683.5 693.7 572.5 611.5 616.3 625.5 630.2 7808.0 8031.5 8087.7 8135.7 8169.5 512.1 534.5 540.0 584.7 616.7 3669.1 3792.3 3922.1 3933.9 3949.1 69338.7 89251.1 89460.2 90666.1 90694.5 72405.3 73673.0 74564.0 75241.6 76614.6 67347.7 68770.7 69077.3 69590.6 70100.6 7222.2 7235.8 7270.6 7470.9 7564.4 47526.8 49010.0 49195.0 49197.5 49356.0 62132.0 64829.4 66407.0 66862.0 70081.6 9828.7 9842.4 10004.1 10104.0 10143.2 10543.7 10858.7 10980.3 11009.6 11526.7 10255.6 10267.6 10295.2 10392.2 10404.2 10199.8 10378.8 10666.7 11725.0 11992.4 11171.7 11359.0 11406.0 11551.7 12117.0 8502.5 9392.0 9431.5 9677.3 9708.5 8454.3 8695.7 8804.5 8824.1 8825.9 13:18:28 INFO - PROCESS | 1666 | -------- Summary: end -------- 13:18:28 INFO - PROCESS | 1666 | 13:18:29 INFO - TEST-INFO | 1666: exit 0 13:18:29 INFO - TEST-OK | dromaeo_css | took 1114798ms 13:18:29 INFO - TEST-START | kraken 13:18:29 INFO - operating with platform_type : mac_ 13:18:29 INFO - Initialising browser for kraken test... 13:18:29 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/tmpdh9hfR/profile http://localhost:49281/getInfo.html) 13:18:31 INFO - PROCESS | 1673 | __metrics Screen width/height:1600/1200 13:18:31 INFO - PROCESS | 1673 | colorDepth:24 13:18:31 INFO - PROCESS | 1673 | Browser inner width/height: 1024/689 13:18:31 INFO - PROCESS | 1673 | __metrics 13:18:31 INFO - PROCESS | 1673 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 13:18:31 ERROR - PROCESS | 1673 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 13:18:32 INFO - TEST-INFO | 1673: exit 0 13:18:32 INFO - Browser initialized. 13:18:32 INFO - Running cycle 1/1 for kraken test... 13:18:32 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/tmpdh9hfR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:18:44 INFO - PROCESS | 1675 | RSS: Main: 210948096 13:18:44 INFO - PROCESS | 1675 | 13:18:46 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-beat-detection) 13:18:47 INFO - PROCESS | 1675 | RSS: Main: 271597568 13:18:47 INFO - PROCESS | 1675 | 13:18:50 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-dft) 13:18:51 INFO - PROCESS | 1675 | RSS: Main: 287694848 13:18:51 INFO - PROCESS | 1675 | 13:18:54 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-fft) 13:18:54 INFO - PROCESS | 1675 | RSS: Main: 372228096 13:18:54 INFO - PROCESS | 1675 | 13:18:56 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-oscillator) 13:18:57 INFO - PROCESS | 1675 | RSS: Main: 279597056 13:18:57 INFO - PROCESS | 1675 | 13:18:59 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49281/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:19:00 INFO - PROCESS | 1675 | RSS: Main: 280141824 13:19:00 INFO - PROCESS | 1675 | 13:19:02 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49281/tests/kraken/driver.html?testName=imaging-darkroom) 13:19:03 INFO - PROCESS | 1675 | RSS: Main: 318722048 13:19:03 INFO - PROCESS | 1675 | 13:19:06 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49281/tests/kraken/driver.html?testName=imaging-desaturate) 13:19:06 INFO - PROCESS | 1675 | RSS: Main: 318816256 13:19:06 INFO - PROCESS | 1675 | 13:19:09 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49281/tests/kraken/driver.html?testName=json-parse-financial) 13:19:10 INFO - PROCESS | 1675 | RSS: Main: 317329408 13:19:10 INFO - PROCESS | 1675 | 13:19:12 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49281/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:19:12 INFO - PROCESS | 1675 | RSS: Main: 308989952 13:19:12 INFO - PROCESS | 1675 | 13:19:14 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:19:15 INFO - PROCESS | 1675 | RSS: Main: 315387904 13:19:15 INFO - PROCESS | 1675 | 13:19:17 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:19:18 INFO - PROCESS | 1675 | RSS: Main: 318844928 13:19:18 INFO - PROCESS | 1675 | 13:19:20 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:19:21 INFO - PROCESS | 1675 | RSS: Main: 320835584 13:19:21 INFO - PROCESS | 1675 | 13:19:24 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:19:24 INFO - PROCESS | 1675 | RSS: Main: 321343488 13:19:24 INFO - PROCESS | 1675 | 13:19:26 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49281/tests/kraken/driver.html?testName=ai-astar) 13:19:26 INFO - PROCESS | 1675 | RSS: Main: 385417216 13:19:26 INFO - PROCESS | 1675 | 13:19:26 INFO - PROCESS | 1675 | __start_tp_report 13:19:26 INFO - PROCESS | 1675 | _x_x_mozilla_page_load 13:19:26 INFO - PROCESS | 1675 | _x_x_mozilla_page_load_details 13:19:26 INFO - PROCESS | 1675 | |i|pagename|runs| 13:19:26 INFO - PROCESS | 1675 | |0;ai-astar;114;113;118;112;114;111;114;114;114;134 13:19:26 INFO - PROCESS | 1675 | |1;audio-beat-detection;156;139;181;187;155;141;117;153;142;138 13:19:26 INFO - PROCESS | 1675 | |2;audio-dft;185;183;183;183;182;182;182;182;183;184 13:19:26 INFO - PROCESS | 1675 | |3;audio-fft;69;79;72;148;56;55;58;66;134;55 13:19:26 INFO - PROCESS | 1675 | |4;audio-oscillator;92;91;137;78;79;92;122;78;76;122 13:19:26 INFO - PROCESS | 1675 | |5;imaging-gaussian-blur;86;84;86;85;126;82;86;83;86;83 13:19:26 INFO - PROCESS | 1675 | |6;imaging-darkroom;93;92;93;93;93;93;96;92;92;88 13:19:26 INFO - PROCESS | 1675 | |7;imaging-desaturate;87;86;101;129;90;85;88;88;87;86 13:19:26 INFO - PROCESS | 1675 | |8;json-parse-financial;61;64;67;65;66;63;65;85;58;58 13:19:26 INFO - PROCESS | 1675 | |9;json-stringify-tinderbox;63;67;73;79;72;68;68;69;68;70 13:19:26 INFO - PROCESS | 1675 | |10;stanford-crypto-aes;94;94;105;69;94;93;104;104;79;86 13:19:26 INFO - PROCESS | 1675 | |11;stanford-crypto-ccm;126;145;141;149;124;127;128;128;186;130 13:19:26 INFO - PROCESS | 1675 | |12;stanford-crypto-pbkdf2;165;168;172;160;166;166;164;164;165;164 13:19:26 INFO - PROCESS | 1675 | |13;stanford-crypto-sha256-iterative;67;66;69;67;68;66;65;67;70;74 13:19:26 INFO - PROCESS | 1675 | __end_tp_report 13:19:26 INFO - PROCESS | 1675 | __start_cc_report 13:19:26 INFO - PROCESS | 1675 | _x_x_mozilla_cycle_collect,2013 13:19:26 INFO - PROCESS | 1675 | __end_cc_report 13:19:26 INFO - PROCESS | 1675 | __startTimestamp1464639566896__endTimestamp 13:19:26 INFO - PROCESS | 1675 | 13:19:26 INFO - PROCESS | 1675 | ------- Summary: start ------- 13:19:26 INFO - PROCESS | 1675 | Number of tests: 14 13:19:26 INFO - PROCESS | 1675 | 13:19:26 INFO - PROCESS | 1675 | [#0] ai-astar Cycles:10 Average:115.80 Median:114.00 stddev:6.65 (5.8%) stddev-sans-first:7.02 13:19:26 INFO - PROCESS | 1675 | Values: 114.0 113.0 118.0 112.0 114.0 111.0 114.0 114.0 114.0 134.0 13:19:26 INFO - PROCESS | 1675 | 13:19:26 INFO - PROCESS | 1675 | [#1] audio-beat-detection Cycles:10 Average:150.90 Median:154.00 stddev:20.79 (13.5%) stddev-sans-first:21.97 13:19:26 INFO - PROCESS | 1675 | Values: 156.0 139.0 181.0 187.0 155.0 141.0 117.0 153.0 142.0 138.0 13:19:26 INFO - PROCESS | 1675 | 13:19:26 INFO - PROCESS | 1675 | [#2] audio-dft Cycles:10 Average:182.90 Median:183.00 stddev:0.99 (0.5%) stddev-sans-first:0.71 13:19:26 INFO - PROCESS | 1675 | Values: 185.0 183.0 183.0 183.0 182.0 182.0 182.0 182.0 183.0 184.0 13:19:26 INFO - PROCESS | 1675 | 13:19:26 INFO - PROCESS | 1675 | [#3] audio-fft Cycles:10 Average:79.20 Median:62.00 stddev:33.71 (54.4%) stddev-sans-first:35.55 13:19:26 INFO - PROCESS | 1675 | Values: 69.0 79.0 72.0 148.0 56.0 55.0 58.0 66.0 134.0 55.0 13:19:26 INFO - PROCESS | 1675 | 13:19:26 INFO - PROCESS | 1675 | [#4] audio-oscillator Cycles:10 Average:96.70 Median:78.50 stddev:22.17 (28.2%) stddev-sans-first:23.45 13:19:26 INFO - PROCESS | 1675 | Values: 92.0 91.0 137.0 78.0 79.0 92.0 122.0 78.0 76.0 122.0 13:19:26 INFO - PROCESS | 1675 | 13:19:26 INFO - PROCESS | 1675 | [#5] imaging-gaussian-blur Cycles:10 Average:88.70 Median:85.50 stddev:13.19 (15.4%) stddev-sans-first:13.96 13:19:26 INFO - PROCESS | 1675 | Values: 86.0 84.0 86.0 85.0 126.0 82.0 86.0 83.0 86.0 83.0 13:19:26 INFO - PROCESS | 1675 | 13:19:26 INFO - PROCESS | 1675 | [#6] imaging-darkroom Cycles:10 Average:92.50 Median:93.00 stddev:1.96 (2.1%) stddev-sans-first:2.07 13:19:26 INFO - PROCESS | 1675 | Values: 93.0 92.0 93.0 93.0 93.0 93.0 96.0 92.0 92.0 88.0 13:19:26 INFO - PROCESS | 1675 | 13:19:26 INFO - PROCESS | 1675 | [#7] imaging-desaturate Cycles:10 Average:92.70 Median:87.00 stddev:13.55 (15.6%) stddev-sans-first:14.21 13:19:26 INFO - PROCESS | 1675 | Values: 87.0 86.0 101.0 129.0 90.0 85.0 88.0 88.0 87.0 86.0 13:19:26 INFO - PROCESS | 1675 | 13:19:26 INFO - PROCESS | 1675 | [#8] json-parse-financial Cycles:10 Average:65.20 Median:65.00 stddev:7.63 (11.7%) stddev-sans-first:7.94 13:19:26 INFO - PROCESS | 1675 | Values: 61.0 64.0 67.0 65.0 66.0 63.0 65.0 85.0 58.0 58.0 13:19:26 INFO - PROCESS | 1675 | 13:19:26 INFO - PROCESS | 1675 | [#9] json-stringify-tinderbox Cycles:10 Average:69.70 Median:69.50 stddev:4.27 (6.1%) stddev-sans-first:3.78 13:19:26 INFO - PROCESS | 1675 | Values: 63.0 67.0 73.0 79.0 72.0 68.0 68.0 69.0 68.0 70.0 13:19:26 INFO - PROCESS | 1675 | 13:19:26 INFO - PROCESS | 1675 | [#10] stanford-crypto-aes Cycles:10 Average:92.20 Median:89.50 stddev:11.56 (12.9%) stddev-sans-first:12.25 13:19:26 INFO - PROCESS | 1675 | Values: 94.0 94.0 105.0 69.0 94.0 93.0 104.0 104.0 79.0 86.0 13:19:26 INFO - PROCESS | 1675 | 13:19:26 INFO - PROCESS | 1675 | [#11] stanford-crypto-ccm Cycles:10 Average:138.40 Median:135.50 stddev:18.88 (13.9%) stddev-sans-first:19.48 13:19:26 INFO - PROCESS | 1675 | Values: 126.0 145.0 141.0 149.0 124.0 127.0 128.0 128.0 186.0 130.0 13:19:26 INFO - PROCESS | 1675 | 13:19:26 INFO - PROCESS | 1675 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.40 Median:165.50 stddev:3.10 (1.9%) stddev-sans-first:3.28 13:19:26 INFO - PROCESS | 1675 | Values: 165.0 168.0 172.0 160.0 166.0 166.0 164.0 164.0 165.0 164.0 13:19:26 INFO - PROCESS | 1675 | 13:19:26 INFO - PROCESS | 1675 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.90 Median:67.50 stddev:2.60 (3.9%) stddev-sans-first:2.74 13:19:26 INFO - PROCESS | 1675 | Values: 67.0 66.0 69.0 67.0 68.0 66.0 65.0 67.0 70.0 74.0 13:19:26 INFO - PROCESS | 1675 | -------- Summary: end -------- 13:19:26 INFO - PROCESS | 1675 | 13:19:27 INFO - TEST-INFO | 1675: exit 0 13:19:27 INFO - TEST-OK | kraken | took 58408ms 13:19:27 INFO - SUITE-END | took 1173s 13:19:28 INFO - Completed test suite (00:19:33) 13:19:28 INFO - javascript benchmark 13:19:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3378.020374091513, 3429.6398878117798, 3447.3967055765233, 3469.2020835207886, 3512.8946131616062, 5639.802606908754, 5659.490645841874, 5661.688607126612, 5680.261565996425, 5682.26130603021, 5652.208690783294, 5700.7719691212405, 5702.600817942745, 5702.942970570293, 5731.512821410175, 5095.770690318945, 5116.641835071556, 5160.974195129038, 5244.842654720364, 5253.238280449333, 5227.817026404076, 5230.215467679859, 5239.083160446926, 5245.0, 5249.028929648028, 5132.332796736419, 5169.974150129263, 5184.326037615105, 5193.428722840485, 5238.659487133325, 5197.584193264549, 5257.316548848626, 5258.474152584749, 5261.5790736741355, 5306.70813105279, 5041.1682072458, 5079.822206222764, 5081.847544573632, 5161.380634323905, 5166.715830629314, 5491.917621235684, 5546.55627549795, 5556.86107847303, 5565.554755619541, 5574.9721251393885, 6164.229471316085, 6208.037754148069, 6227.221597300338, 6230.595011324294, 6236.282827474808, 4739.549742774431, 6191.288001879797, 6263.937360626381, 6289.308176100625, 6289.433950944437, 5823.068309070529, 5836.766529338822, 5841.970790146022, 5847.1229315603, 5848.2689663792025, 6260.217472815898, 6263.592866463665, 6271.717772700239, 6274.843128921722, 6281.654509002049, 5961.523078153824, 6025.739282763823, 6055.666938318434, 6073.726682299307, 6160.9383906160365, 6122.785702500391, 6136.978641397228, 6138.756267604181, 6157.7229024694, 6168.537359698041, 6145.678679083929, 6219.906701399483, 6220.657863817533, 6246.281677607042, 6256.217972753406, 6173.290071641819, 6207.441330280206, 6209.223847019122, 6230.190075290274, 6245.094461303085, 4837.830675926396, 4906.656534042583, 4929.014197160511, 4932.778024988884, 4950.950490495121, 4354.651627869763, 4386.990992071841, 4389.078293558388, 4389.956100439019, 4438.245498265303, 5664.150377443417, 5682.707209025336, 5693.316801983706, 5698.202251684767, 5755.079187330007, 5996.280446346379, 6031.185789918392, 6186.19579454677, 6241.157443745036, 6273.215848018997, 5580.023495888203, 5589.413111623221, 5592.748326325243, 5604.915926261109, 5605.355384130796, 5414.83755487336, 5437.673739575638, 5443.292371991616, 5468.316460442445, 5490.176473529003, 6336.778212762623, 6354.777582784581, 6356.300806911236, 6361.777337793247, 6365.522585806083, 6121.785737499166, 6191.690415479118, 6246.3753624637175, 6250.749970001331, 6262.874742505215, 6280.183576135073, 6300.180976473029, 6327.335629758809, 6328.683565821599, 6338.809835704937, 6171.660558669317, 6176.4441200292185, 6179.907301390393, 6186.969065154645, 6205.6586887721605, 6004.129401236709, 6213.2233470816145, 6258.812235632939, 6266.811995640138, 6279.811605651837, 5849.853753656193, 5986.401359864153, 6054.273487181566, 6061.030235162353, 6066.332703402711, 6036.3661815510495, 6062.757489700362, 6069.696515174312, 6073.848153796013, 6080.756769729338, 5635.88728225425, 5643.984082865124, 5773.8556536086935, 5905.557083218861, 5940.257467816523, 5879.617824841372, 5890.7349169288345, 5927.436893495111, 5967.76128954837, 5975.282966043928, 5003.949960500348, 5797.304323481209, 5824.563157763269, 5832.212651292021, 5846.649201047951], "value": 5662.2619947937055, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [517.2913109040612, 522.9697495933012, 547.7891011960394, 548.9313835770529, 599.2090440618385, 30428.84785576072, 31032.379352412965, 37203.99999999996, 38465.80767096161, 38478.845634631005, 40257.59742402582, 40721.796391018004, 40741.77774666765, 40796.0, 40896.0, 9351.298652601065, 9552.904470955305, 9571.473568953705, 9587.90412095877, 9592.952035239814, 8322.0, 8546.359023073295, 10744.14046876252, 11620.0, 11670.47482863269, 11373.260738052, 11449.885501145007, 11451.96932276095, 11474.0, 11575.958163765259, 8545.786355341104, 11285.379304138269, 11370.71573210668, 11376.943115284454, 11579.652610421701, 11422.942885285604, 11551.191416600841, 11564.479598418091, 11572.710682232928, 11575.305481671126, 38034.42948355777, 38390.0, 38478.615213847785, 38520.80739596284, 38534.03664908372, 3153.984230078835, 3193.872245110216, 3365.175531994677, 3760.830762615689, 3809.3904975204114, 27471.587926181124, 27922.581161282596, 28110.0, 28303.0, 28449.857750711115, 2731.6039174319612, 2749.766269867064, 2765.861706914646, 2804.803663743539, 2819.7321254481, 441040.794796024, 445843.0, 447401.0, 448498.0, 449207.7539612314, 62699.6865015672, 63001.68499157475, 63169.05246421308, 63416.68291658512, 63444.68277658674, 68733.3126668737, 70179.29820701727, 70217.64891175511, 70258.0, 70279.0, 17483.213255403283, 17687.911560442117, 17710.29158833658, 17728.84762490434, 17810.821891781176, 12497.687557810945, 12747.171433856769, 12759.93620031894, 13371.933140334237, 13503.662408439868, 17156.22796974139, 17384.4784656458, 19548.36938892626, 20024.898630575168, 20233.49416264576, 8003.399320135996, 8115.75652730419, 8149.492343916394, 8194.54929978845, 8211.630476628567, 946.1153821177221, 975.6975337645353, 1001.7495626093477, 1056.0970370333407, 1126.8197088465806, 28480.715192847805, 28500.42999140047, 28515.714842851306, 28999.42001159965, 29064.273393164916, 932.8134373125403, 1096.31480324797, 1101.8656476306805, 1140.560884059643, 1146.4726225935997, 12851.807222891664, 15616.531504054896, 15763.763543546856, 16019.359225630844, 16403.6719265614, 583785.0, 586112.0, 589089.0, 589415.0, 593185.0340748269, 60623.09065364023, 60985.69507152436, 61012.69493652592, 61113.0, 61161.69419152965, 1399.8740113389845, 1492.8283247426684, 1517.7723341498863, 1526.137732181314, 1534.823495298055, 27954.86022569874, 28461.857690711415, 28705.28236794097, 29195.270118247212, 29379.412411752073, 51291.974160515834, 51440.22839657483, 52226.0, 52442.95114097773, 52463.0, 505815.0, 508906.09922554315, 509874.0, 510025.0, 510914.0, 23715.64426533569, 26776.732232677423, 26856.0, 27081.59377609298, 27342.863285683445, 39873.0, 41227.58772412237, 41511.7924410376, 41936.58063419327, 42203.1559368817, 34070.737321485634, 34583.0, 36290.63709362873, 36866.44700329444, 37041.44437833488, 35570.46644300286, 35790.0, 35981.0, 36569.45145822762, 36771.0, 35913.64086359103, 36198.914032579065, 36199.45700814542, 36356.09109772277, 36602.26795464129, 31300.843495782374, 31891.202719932186, 31927.0, 31954.84022579872, 32065.519017214294, 23875.16436924699, 24361.634575481028, 24364.531678052896, 24542.877285613456, 24748.886300116897], "value": 19939.58703937341, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.27437585145185, 405.4465654381769, 408.4955080475613, 408.8446390371656, 421.17870153201244, 18624.72062919054, 19842.60314793705, 20445.89777051113, 20449.386518404433, 20483.487912802186, 19911.601767964632, 20122.49693757657, 20206.696899546518, 21571.0, 21577.568448631017, 5310.415854256048, 5677.233573467571, 5911.231539899818, 5999.880002399971, 6122.8469288267925, 5477.917831232535, 5967.254093238345, 5988.880222395549, 6014.278286605613, 6036.0, 5688.800891968782, 5755.740991655385, 6024.126501657257, 6074.787382441616, 6086.634801911878, 6000.159977603139, 6020.96989515054, 6036.758529658852, 6046.758129674808, 6051.636901785885, 5436.320459942507, 5740.9712951435395, 5754.050581654022, 5830.0, 5848.41515848416, 12996.675083122904, 13439.865601344058, 13563.525276615272, 13630.591082267549, 13671.0, 1726.9481915542553, 2228.1310288987306, 2303.9045013678187, 2375.4298968247667, 2819.6616406031203, 9666.130048295687, 10111.342762720473, 10167.491625418774, 12166.026717862549, 14920.701585968329, 1607.7427611582207, 1760.8679349048743, 1763.6208215233657, 1769.4868488138425, 1775.5649865782837, 96947.0, 115436.0, 115635.42182289119, 118873.81126188714, 119443.0, 9790.461524616214, 10249.9487502562, 11182.713987891333, 11865.525378984916, 11992.580259690869, 15274.31265593051, 15298.770518442232, 15299.923500382427, 15306.234688265542, 15338.923305383401, 8897.955510222537, 9088.909110908806, 9095.954520227357, 9182.265418766483, 9188.90811091894, 9095.181433671001, 9116.680916168036, 9128.406653567497, 9221.0, 9234.630614775468, 4095.569965153616, 4419.784559246169, 4741.099191153669, 4754.857354279377, 4782.736949467812, 1937.3413039566615, 1970.787965401268, 1989.532459871929, 1999.7700264469772, 2035.8574899756875, 895.3911340288536, 980.8724865767405, 1005.9295849290479, 1005.9899401005896, 1031.3450958641317, 20091.39725808228, 20371.592568148553, 20489.97550122488, 20503.897480512504, 20557.0, 966.917811985975, 970.8689326940914, 989.4805227255748, 992.0426788149326, 1001.7127990532131, 9278.932922713839, 10328.690139295833, 10542.683719488274, 10623.990720881504, 10698.358098514114, 584395.0, 603880.0, 609822.0, 619866.0, 622861.0, 112393.0, 112500.43749781199, 114788.42605786918, 115168.0, 118768.0, 1015.8323876560432, 1027.1525991057258, 1034.281174583657, 1052.752603138262, 1055.9313644613076, 17350.132493375535, 17372.044537550555, 17575.912120439316, 17639.470815875033, 18706.532336691693, 49438.011239775726, 50328.0, 50529.32389426547, 51936.48063519316, 51949.480505194464, 64048.67975660092, 67502.46091271004, 68061.0, 68120.65939670468, 68622.0, 8367.414281000272, 8367.707130250656, 8478.57607119654, 8485.830283394174, 8768.25469835071, 12122.575709850113, 12672.809907851206, 12895.742085158057, 13128.212307261594, 13164.80252796228, 11129.833052504056, 11307.943460282646, 11396.08831293478, 11471.0, 11499.0, 10938.343699378063, 10980.0, 10997.890021100007, 11210.495527701436, 11430.885691142983, 11338.489767960296, 11411.201215914807, 11584.131190160602, 11740.41297935117, 11746.588869389872, 8372.618517944715, 8382.371322150984, 8384.245417912172, 8448.662053518035, 8470.618822152896, 10704.625338113128, 10875.456227188768, 11431.0, 11479.598214062467, 11538.0], "value": 9463.423247050307, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1414.0243232169796, 1575.1572908493954, 1599.2243761775537, 1643.9314445610353, 1986.0566231040257, 7318.0, 7810.0, 8042.4772389794625, 8683.956580217106, 8685.218330350268, 8444.831103377945, 8500.107488713673, 8588.184122508352, 8617.009043959946, 8636.13638636136, 2179.4769255378756, 2423.248792874215, 2440.2679196241056, 2445.9143929962456, 2449.0448724997264, 2386.7016622922133, 2418.613021916494, 2433.221369161869, 2436.8050555955483, 2436.9512609747794, 2375.7743014413604, 2414.770596793302, 2420.044082587377, 2420.4674971506242, 2423.212455951814, 2366.4438856868624, 2406.0856874387796, 2412.0231306320966, 2415.033986405443, 2426.296374051523, 2360.7285162206394, 2410.421498840283, 2414.010255795133, 2414.2274472168915, 2417.033186725324, 8703.95648021756, 8764.824703505958, 8772.692955746517, 8773.473591584461, 8789.516576588285, 4471.910561788746, 5430.59270554706, 5458.6724796511935, 5474.562035037227, 5479.698616576086, 3384.069380920242, 3389.7288216942584, 3391.5930088389305, 3440.9827950860335, 3445.776024558421, 1037.7457522906861, 1045.2108657963265, 1049.5539395756773, 1057.3285963413289, 1062.6918193723811, 11708.88291117095, 11802.46888890002, 11805.0, 11816.409179540988, 11827.64517064498, 4552.453705555288, 4760.809567617326, 4889.315495830587, 4890.070886531547, 4907.1657818171, 9133.178013978773, 9162.633494660273, 9229.261659067259, 9246.4914429707, 9246.768830779285, 5269.446708095671, 5291.259223708684, 5299.33758280215, 5306.310179676694, 5314.548263397654, 4138.337865941495, 4268.637165840876, 4381.430414046154, 4428.1365133799245, 4915.754212289371, 3779.4519794629623, 3858.151206734514, 3860.652541271299, 3884.76691398517, 3887.3391523441082, 1168.7195073182374, 1184.691980085184, 1195.235049568277, 1199.682084247675, 1202.2606097250127, 843.9324854011787, 873.9257163141077, 905.0270958719403, 907.2197909797569, 924.6578765856676, 5791.420857914259, 5867.178594996704, 5970.671613061237, 6005.789797357072, 6132.08018797184, 462.4774005373906, 462.9444466664022, 465.50888812303344, 471.62505807882815, 481.87712133405756, 3907.29668659644, 3964.801759911993, 3968.0675041365257, 4006.2187873364414, 4056.6146216110014, 10318.122959548522, 10628.202884783672, 10711.94644026775, 10730.034296913314, 10755.462226888625, 10452.790944181073, 10609.57561697539, 10616.628418005334, 10707.785844283071, 10972.0, 7829.921700783033, 8243.340532757366, 8319.001719793663, 8323.791905202419, 8341.0, 5729.742161602821, 5835.766569337179, 5880.50015748649, 5933.940660593339, 5958.374370691015, 9211.631534738535, 9303.116203960613, 9337.0, 9412.623495060396, 9444.244460443011, 9297.488638124969, 9435.433873967584, 9438.292128090558, 9543.856842147512, 9621.037896210322, 5824.592278540631, 6547.672616369067, 6697.933020669926, 7347.632618368953, 7440.813979650552, 7051.294870512907, 8225.136360682041, 8280.420370574002, 8295.668173273001, 8303.916960830556, 6246.0, 7301.306375894282, 7362.705491780269, 7384.446166537424, 7531.510451820614, 7241.637918104179, 7257.201707812031, 7306.561606303639, 7323.304286092813, 7339.302766237199, 6839.179298484214, 6924.619145947021, 6953.617551034742, 6957.1651401832105, 6957.37383635455, 4714.622830173646, 4727.645426592951, 4750.501197374226, 4848.806047758191, 4868.2940973557925, 4152.626263636287, 4761.142994261067, 4846.176150054429, 4959.479254678206, 5013.448520662797], "value": 4427.406082401598, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [326.7647293948357, 329.17377382769257, 335.3762002675025, 338.94237979543476, 341.3003343146549, 6496.5777224480435, 6500.219973603168, 6501.414872661459, 6557.311482294362, 6559.639219842907, 6447.54867159299, 6464.579802312846, 6490.707918143683, 6503.414692677658, 6525.673716314189, 4564.634829213664, 4679.60223381013, 4688.390509233812, 4711.90576188476, 4824.831130910419, 4307.461567304087, 4689.92131809684, 4773.379460670126, 4780.784864681098, 4802.375691160144, 4635.096156249533, 4732.763361831911, 4734.124187025412, 4740.431148262213, 4752.002079563295, 4546.386237857913, 4599.586037256663, 4713.245880659112, 4744.027474367746, 4797.18447863864, 4057.1074363640246, 4069.796510174509, 4073.388991651246, 4075.1442197138635, 4082.9183416331803, 5374.596905232123, 5684.403137670526, 6088.482478989291, 6196.442320191204, 6197.597156184878, 5097.362829646294, 5646.57650676201, 5662.433756624346, 5665.801696940587, 5667.433256674341, 6976.0, 6991.161060672701, 6998.720051197946, 7004.649767511604, 7009.82475438113, 4369.344598310247, 4536.319552067216, 4579.610733087691, 4611.930821037688, 4627.167109920213, 7310.0, 7324.377427918634, 7355.117385913671, 7355.9701641770735, 7366.226546212624, 4589.770511474413, 4595.2188128018315, 4596.0, 4605.539446055368, 4612.100640375094, 4654.325122857167, 4660.13787449323, 4664.766761661904, 4684.882877928011, 4692.671512994058, 5520.282363292747, 5553.333599968029, 5555.2778138841695, 5564.33228012641, 5572.721363931787, 5701.71491425427, 6873.862522749617, 7021.22766495685, 7022.367986881205, 7041.0, 1600.039976014382, 1617.8139513956046, 1629.5600187949187, 1633.9591510212101, 1638.0, 1017.4048181813574, 1018.4449475036123, 1038.8182068138046, 1042.8592140060994, 1043.4313299251774, 5618.971905140448, 5999.0, 6269.310375858652, 6506.0, 6725.798226053226, 7411.184769675331, 7414.814629634302, 7418.480706350504, 7428.442866784904, 7433.331000209899, 6651.700673469705, 6665.433438157714, 6774.220964589135, 6813.897791533131, 6823.044773731682, 3842.058695619591, 3891.494105766232, 3894.766314021168, 3896.980515097463, 3898.2203559288255, 7431.145418276859, 7755.3407960324, 7764.184760600168, 7788.922110778933, 7827.569483678452, 6873.381395674313, 7573.507721998052, 7595.3164215220895, 7623.656935437919, 7634.580098094658, 7689.461737678309, 7724.575148366894, 7729.768106956801, 7730.884036739453, 7735.497192682457, 7060.3645671887725, 7336.523125996793, 7342.70629174827, 7349.375303099297, 7368.521046131984, 5962.344142144274, 7148.67830947619, 7194.496385252982, 7216.7834964951135, 7219.0976127984, 7725.99562056929, 7753.418493612889, 7781.455298129076, 7783.338416234684, 7788.299053085249, 4581.198290299251, 4636.768161591839, 4638.814447422065, 4640.419947506562, 4641.535846415331, 7052.400545953446, 7301.744438944612, 7315.707371705073, 7326.194118647051, 7333.816654583678, 7066.293370662893, 7116.608586527791, 7119.181294151032, 7121.857562848818, 7140.821479463055, 6100.420460056288, 7055.400290975119, 7077.36303732646, 7106.751263705745, 7109.28907109285, 5666.886662266649, 6906.447484201352, 6917.446604271747, 6920.134983127109, 6927.099477067949, 5967.37342579023, 6655.500837437308, 7079.787606371817, 7093.680784364613, 7102.218755936739, 5370.543503802221, 6406.750683616837, 6625.966870165618, 6930.930690693029, 6931.75738849158], "value": 5098.250915445951, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [696.1854630082803, 741.6588369350098, 757.981050473738, 761.6039659377127, 800.4516905919444, 12263.570775022876, 12979.80530292044, 14606.758425533815, 15054.623634409145, 15077.321520531576, 13219.669508262274, 13572.796408053888, 13612.659683507918, 13663.795043074362, 13689.178649281026, 742.5841528743894, 755.4221020919001, 924.9501815439457, 941.0401390581603, 948.0945696859483, 744.4342299852125, 752.9721929566131, 755.2636179724752, 927.2952556057411, 948.7059011706393, 979.3242662562809, 983.7540614846289, 985.7042887133867, 986.758244230161, 988.6440881282732, 977.3402953006728, 979.8726165598426, 980.2794945714894, 980.9460479673616, 982.5578489679637, 554.334798242107, 577.6534079552235, 584.0713265907228, 727.2509315405089, 740.4372676765643, 14804.629884252872, 14885.851141488554, 14900.180490073042, 14903.956723029392, 14929.626759331104, 12128.878711212863, 12152.756944861054, 12153.331566763825, 12425.0, 12698.555550555779, 10071.69784906454, 10109.39343639384, 10191.23565732573, 10357.378557286587, 10377.169826413945, 2996.8501574921165, 3397.286569820341, 3477.600075991268, 3490.458978858281, 3524.6122926478065, 83424.58287708522, 84198.57900710519, 84211.15788842094, 84344.15655843486, 84457.3108537832, 8927.0, 9099.681511147079, 9372.5313734313, 9405.0, 9424.528773561295, 20663.690044649342, 20667.06998185085, 20704.964751762654, 20716.689249661267, 20735.792642073688, 7331.9266807332315, 7371.594562299125, 7473.85052298951, 7494.925050749532, 7761.0298712660915, 4068.0, 4092.3043082676018, 4154.480689913761, 4190.224808410393, 4338.978305108454, 3572.821358932095, 3631.5642122945414, 3634.400323946572, 3641.817909104534, 3642.581103173116, 661.110805965975, 665.7769647168253, 670.5339788846704, 683.460066547422, 693.7225109956058, 572.5276646766434, 611.538288592119, 616.2881871438495, 625.4652272307112, 630.2311180360045, 7807.960960195162, 8031.518108913484, 8087.716929907425, 8135.7152499662225, 8169.489841537903, 512.0578136229282, 534.5082524077858, 540.0414264680225, 584.739790793093, 616.7070641445367, 3669.101070237809, 3792.3173828711097, 3922.1371298314325, 3933.940990885139, 3949.0917089069676, 69338.65330673315, 89251.10748892558, 89460.21079578371, 90666.09333906576, 90694.54652726694, 72405.27594724091, 73673.0, 74564.0, 75241.62379188069, 76614.61692691501, 67347.66326168436, 68770.65614671995, 69077.3092269081, 69590.60818783595, 70100.64949675219, 7222.205557388788, 7235.782926512213, 7270.600116993616, 7470.887936680845, 7564.357029652321, 47526.76236618795, 49010.0, 49195.0, 49197.50802491929, 49356.0, 62132.0, 64829.351706484216, 66407.0, 66862.0, 70081.64959175172, 9828.705138845846, 9842.360246583949, 10004.149647279772, 10104.04011618895, 10143.239257055602, 10543.736406589896, 10858.728531786768, 10980.286281391684, 11009.61466348674, 11526.654200374001, 10255.589776409159, 10267.640632578123, 10295.176385889261, 10392.22058345612, 10404.219683523615, 10199.847002294822, 10378.808605894788, 10666.73333166646, 11725.0, 11992.400379980792, 11171.66485005451, 11359.034482068786, 11406.0, 11551.711207219887, 12116.970057545208, 8502.489850608983, 9391.953040235028, 9431.528423578931, 9677.274204434554, 9708.466034368177, 8454.323654107533, 8695.695650652196, 8804.459674116097, 8824.073472285318, 8825.867611985954], "value": 6727.051832213245, "unit": "score"}], "name": "dromaeo_css", "value": 7385.243986217242, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 118.0, 112.0, 114.0, 111.0, 114.0, 114.0, 114.0, 134.0], "value": 115.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 139.0, 181.0, 187.0, 155.0, 141.0, 117.0, 153.0, 142.0, 138.0], "value": 150.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 183.0, 183.0, 183.0, 182.0, 182.0, 182.0, 182.0, 183.0, 184.0], "value": 182.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 79.0, 72.0, 148.0, 56.0, 55.0, 58.0, 66.0, 134.0, 55.0], "value": 79.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 91.0, 137.0, 78.0, 79.0, 92.0, 122.0, 78.0, 76.0, 122.0], "value": 96.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 86.0, 85.0, 126.0, 82.0, 86.0, 83.0, 86.0, 83.0], "value": 88.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 93.0, 93.0, 93.0, 93.0, 96.0, 92.0, 92.0, 88.0], "value": 92.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 101.0, 129.0, 90.0, 85.0, 88.0, 88.0, 87.0, 86.0], "value": 92.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 64.0, 67.0, 65.0, 66.0, 63.0, 65.0, 85.0, 58.0, 58.0], "value": 65.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 67.0, 73.0, 79.0, 72.0, 68.0, 68.0, 69.0, 68.0, 70.0], "value": 69.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 105.0, 69.0, 94.0, 93.0, 104.0, 104.0, 79.0, 86.0], "value": 92.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 145.0, 141.0, 149.0, 124.0, 127.0, 128.0, 128.0, 186.0, 130.0], "value": 138.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 168.0, 172.0, 160.0, 166.0, 166.0, 164.0, 164.0, 165.0, 164.0], "value": 165.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 69.0, 67.0, 68.0, 66.0, 65.0, 67.0, 70.0, 74.0], "value": 67.9, "unit": "score"}], "name": "kraken", "value": 1498.2000000000005, "alertThreshold": 2.0}]} 13:19:28 INFO - Return code: 0 13:19:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 13:19:28 ERROR - # TBPL SUCCESS # 13:19:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:19:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:19:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:19:28 INFO - 2016-05-30 13:19:28.182 screenresolution[1679:9950] starting screenresolution argv=screenresolution get 13:19:28 INFO - 2016-05-30 13:19:28.186 screenresolution[1679:9950] Display 0: 1600x1200x32@60 13:19:28 INFO - 2016-05-30 13:19:28.192 screenresolution[1680:9953] starting screenresolution argv=screenresolution list 13:19:28 INFO - Available Modes on Display 0 13:19:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:19:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:19:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:19:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:19:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:19:28 INFO - Intel Iris: 13:19:28 INFO - Chipset Model: Intel Iris 13:19:28 INFO - Type: GPU 13:19:28 INFO - Bus: Built-In 13:19:28 INFO - VRAM (Dynamic, Max): 1536 MB 13:19:28 INFO - Vendor: Intel (0x8086) 13:19:28 INFO - Device ID: 0x0a2e 13:19:28 INFO - Revision ID: 0x0009 13:19:28 INFO - Displays: 13:19:28 INFO - Display: 13:19:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:19:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:19:28 INFO - Display Serial Number: 1600x1200 60 13:19:28 INFO - Main Display: Yes 13:19:28 INFO - Mirror: Off 13:19:28 INFO - Online: Yes 13:19:28 INFO - Rotation: Supported 13:19:28 INFO - Return code: 0 13:19:28 INFO - Running post-action listener: _resource_record_post_action 13:19:28 INFO - Running post-run listener: _resource_record_post_run 13:19:29 INFO - Total resource usage - Wall time: 1206s; CPU: 53.0%; Read bytes: 36865536; Write bytes: 655684096; Read time: 240; Write time: 1843 13:19:29 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 354501120; Write bytes: 347490304; Read time: 26534; Write time: 709 13:19:29 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 29761024; Write bytes: 297400832; Read time: 182; Write time: 1098 13:19:29 INFO - Running post-run listener: _upload_blobber_files 13:19:29 INFO - Blob upload gear active. 13:19:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:19:29 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 13:19:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:19:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:19:29 INFO - (blobuploader) - INFO - Open directory for files ... 13:19:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:19:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:19:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:19:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:19:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:19:30 INFO - (blobuploader) - INFO - Done attempting. 13:19:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:19:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:19:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:19:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:19:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:19:32 INFO - (blobuploader) - INFO - Done attempting. 13:19:32 INFO - (blobuploader) - INFO - Iteration through files over. 13:19:32 INFO - Return code: 0 13:19:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:19:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:19:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7fd85dcd7e159c2821061844f1abd0d2b236c9c6f1633ab48307e38a7bf6eb76c55cccf16f245653e0150d4036236c0ab254bf4aa726b15b430d2c619232768d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/574e5a6012025e28181fb86c0fa10e9b5da9f0a7bd52f11a19a861179c8f767349f26a6a3a735fe4a41f2bef3547b28ec9154b523fd445cca67fca4e35816ceb"} 13:19:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:19:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:19:32 INFO - Contents: 13:19:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7fd85dcd7e159c2821061844f1abd0d2b236c9c6f1633ab48307e38a7bf6eb76c55cccf16f245653e0150d4036236c0ab254bf4aa726b15b430d2c619232768d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/574e5a6012025e28181fb86c0fa10e9b5da9f0a7bd52f11a19a861179c8f767349f26a6a3a735fe4a41f2bef3547b28ec9154b523fd445cca67fca4e35816ceb"} 13:19:32 INFO - Running post-run listener: copy_logs_to_upload_dir 13:19:32 INFO - Copying logs to upload dir... 13:19:32 INFO - mkdir: /builds/slave/test/build/upload/logs 13:19:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.851346 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-05-30 13:19:32.126317) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 13:19:32.132150) ========= 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.tNXkH2dPO2/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.SyAF0xPJk1/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7fd85dcd7e159c2821061844f1abd0d2b236c9c6f1633ab48307e38a7bf6eb76c55cccf16f245653e0150d4036236c0ab254bf4aa726b15b430d2c619232768d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/574e5a6012025e28181fb86c0fa10e9b5da9f0a7bd52f11a19a861179c8f767349f26a6a3a735fe4a41f2bef3547b28ec9154b523fd445cca67fca4e35816ceb"} build_url:https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010855 build_url: 'https://queue.taskcluster.net/v1/task/WNM7egHOTQ-OgSE2ODtzGw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7fd85dcd7e159c2821061844f1abd0d2b236c9c6f1633ab48307e38a7bf6eb76c55cccf16f245653e0150d4036236c0ab254bf4aa726b15b430d2c619232768d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/574e5a6012025e28181fb86c0fa10e9b5da9f0a7bd52f11a19a861179c8f767349f26a6a3a735fe4a41f2bef3547b28ec9154b523fd445cca67fca4e35816ceb"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 13:19:32.165721) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 13:19:32.166057) ========= 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.tNXkH2dPO2/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.SyAF0xPJk1/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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.005177 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 13:19:32.218619) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 13:19:32.219202) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 13:19:32.219814) ========= ========= Total master_lag: 0.20 =========