builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0112 starttime: 1462872446.26 results: success (0) buildid: 20160510004007 builduid: 2f6663a6289544ba920cf23088795f59 revision: 63b7cc21ae7cf57d72b1a3a1b169ba3ea9fc237d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-10 02:27:26.264199) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-10 02:27:26.268286) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-10 02:27:26.268610) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-10 02:27:26.299095) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 02:27:26.299505) ========= 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.dVULbzvWip/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.xEQdmiXgSU/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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-10 02:27:26-- 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% 8.64M=0.001s 2016-05-10 02:27:26 (8.64 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.083370 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 02:27:26.406632) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 02:27:26.407107) ========= 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.dVULbzvWip/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.xEQdmiXgSU/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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.020993 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-10 02:27:26.458275) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-10 02:27:26.458887) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 63b7cc21ae7cf57d72b1a3a1b169ba3ea9fc237d --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 63b7cc21ae7cf57d72b1a3a1b169ba3ea9fc237d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dVULbzvWip/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.xEQdmiXgSU/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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-10 02:27:26,530 truncating revision to first 12 chars 2016-05-10 02:27:26,531 Setting DEBUG logging. 2016-05-10 02:27:26,531 attempt 1/10 2016-05-10 02:27:26,531 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/63b7cc21ae7c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-10 02:27:28,510 unpacking tar archive at: mozilla-aurora-63b7cc21ae7c/testing/mozharness/ program finished with exit code 0 elapsedTime=2.231350 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-10 02:27:28.709332) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-10 02:27:28.709791) ========= script_repo_revision: 63b7cc21ae7cf57d72b1a3a1b169ba3ea9fc237d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-10 02:27:28.710328) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-10 02:27:28.710798) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-10 02:27:28.726832) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-05-10 02:27:28.727512) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.dVULbzvWip/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.xEQdmiXgSU/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 02:27:28 INFO - MultiFileLogger online at 20160510 02:27:28 in /builds/slave/test 02:27:28 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:27:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:27:28 INFO - {'append_to_log': False, 02:27:28 INFO - 'base_work_dir': '/builds/slave/test', 02:27:28 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:27:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:27:28 INFO - 'branch': 'Mozilla-Aurora', 02:27:28 INFO - 'buildbot_json_path': 'buildprops.json', 02:27:28 INFO - 'config_files': ('talos/mac_config.py',), 02:27:28 INFO - 'default_actions': ('clobber', 02:27:28 INFO - 'read-buildbot-config', 02:27:28 INFO - 'download-and-extract', 02:27:28 INFO - 'populate-webroot', 02:27:28 INFO - 'create-virtualenv', 02:27:28 INFO - 'install', 02:27:28 INFO - 'run-tests'), 02:27:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:27:28 INFO - 'download_minidump_stackwalk': True, 02:27:28 INFO - 'download_symbols': 'ondemand', 02:27:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:27:28 INFO - 'tooltool.py': '/tools/tooltool.py', 02:27:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:27:28 INFO - '/tools/misc-python/virtualenv.py')}, 02:27:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:27:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:27:28 INFO - 'installer_path': 'installer.exe', 02:27:28 INFO - 'log_level': 'info', 02:27:28 INFO - 'log_name': 'talos', 02:27:28 INFO - 'log_to_console': True, 02:27:28 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:27:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:27:28 INFO - 'opt_config_files': (), 02:27:28 INFO - 'pip_index': False, 02:27:28 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:27:28 INFO - 'cmd': ('bash', 02:27:28 INFO - '-c', 02:27:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:27:28 INFO - 'enabled': True, 02:27:28 INFO - 'halt_on_failure': False, 02:27:28 INFO - 'name': 'check_screen_resolution'},), 02:27:28 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:27:28 INFO - 'cmd': ('bash', 02:27:28 INFO - '-c', 02:27:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:27:28 INFO - 'enabled': True, 02:27:28 INFO - 'halt_on_failure': False, 02:27:28 INFO - 'name': 'check_screen_resolution'},), 02:27:28 INFO - 'run_cmd_checks_enabled': True, 02:27:28 INFO - 'sps_profile': False, 02:27:28 INFO - 'sps_profile_interval': 0, 02:27:28 INFO - 'suite': 'dromaeojs-e10s', 02:27:28 INFO - 'system_bits': '32', 02:27:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:27:28 INFO - 'title': 't-yosemite-r7-0112', 02:27:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:27:28 INFO - 'use_talos_json': True, 02:27:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:27:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:27:28 INFO - 'work_dir': 'build'} 02:27:28 INFO - ##### 02:27:28 INFO - ##### Running clobber step. 02:27:28 INFO - ##### 02:27:28 INFO - Running pre-action listener: _resource_record_pre_action 02:27:28 INFO - Running main action method: clobber 02:27:28 INFO - rmtree: /builds/slave/test/build 02:27:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:27:30 INFO - Running post-action listener: _resource_record_post_action 02:27:30 INFO - ##### 02:27:30 INFO - ##### Running read-buildbot-config step. 02:27:30 INFO - ##### 02:27:30 INFO - Running pre-action listener: _resource_record_pre_action 02:27:30 INFO - Running main action method: read_buildbot_config 02:27:30 INFO - Using buildbot properties: 02:27:30 INFO - { 02:27:30 INFO - "project": "", 02:27:30 INFO - "product": "firefox", 02:27:30 INFO - "script_repo_revision": "production", 02:27:30 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 02:27:30 INFO - "repository": "", 02:27:30 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 02:27:30 INFO - "buildid": "20160510004007", 02:27:30 INFO - "stage_platform": "macosx64", 02:27:30 INFO - "basedir": "/builds/slave/test", 02:27:30 INFO - "nightly_build": "True", 02:27:30 INFO - "buildnumber": 321, 02:27:30 INFO - "slavename": "t-yosemite-r7-0112", 02:27:30 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 02:27:30 INFO - "platform": "yosemite_r7", 02:27:30 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:27:30 INFO - "branch": "mozilla-aurora", 02:27:30 INFO - "repo_path": "releases/mozilla-aurora", 02:27:30 INFO - "revision": "63b7cc21ae7cf57d72b1a3a1b169ba3ea9fc237d", 02:27:30 INFO - "pgo_build": "False", 02:27:30 INFO - "builduid": "2f6663a6289544ba920cf23088795f59", 02:27:30 INFO - "slavebuilddir": "test" 02:27:30 INFO - } 02:27:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/aOXVkNIVT7isTwgD8DrGYA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 02:27:30 INFO - Running post-action listener: _resource_record_post_action 02:27:30 INFO - ##### 02:27:30 INFO - ##### Running download-and-extract step. 02:27:30 INFO - ##### 02:27:30 INFO - Running pre-action listener: _resource_record_pre_action 02:27:30 INFO - Running main action method: download_and_extract 02:27:30 INFO - mkdir: /builds/slave/test/build/tests 02:27:30 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')]} 02:27:30 INFO - https://queue.taskcluster.net/v1/task/aOXVkNIVT7isTwgD8DrGYA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 02:27:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aOXVkNIVT7isTwgD8DrGYA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:27:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aOXVkNIVT7isTwgD8DrGYA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:27:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aOXVkNIVT7isTwgD8DrGYA/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 02:27:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aOXVkNIVT7isTwgD8DrGYA/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 02:27:32 INFO - Downloaded 1199 bytes. 02:27:32 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 02:27:32 INFO - Using the following test package requirements: 02:27:32 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:27:32 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:27:32 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 02:27:32 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:27:32 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 02:27:32 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:27:32 INFO - u'jsshell-mac.zip'], 02:27:32 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:27:32 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 02:27:32 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:27:32 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:27:32 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 02:27:32 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:27:32 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 02:27:32 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:27:32 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 02:27:32 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:27:32 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 02:27:32 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:27:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:27:32 INFO - https://queue.taskcluster.net/v1/task/aOXVkNIVT7isTwgD8DrGYA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:27:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aOXVkNIVT7isTwgD8DrGYA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:27:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aOXVkNIVT7isTwgD8DrGYA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:27:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aOXVkNIVT7isTwgD8DrGYA/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 02:27:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aOXVkNIVT7isTwgD8DrGYA/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 02:27:33 INFO - Downloaded 18089264 bytes. 02:27:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:27:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:27:36 INFO - Return code: 0 02:27:36 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 02:27:36 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')]} 02:27:36 INFO - https://queue.taskcluster.net/v1/task/aOXVkNIVT7isTwgD8DrGYA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:27:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aOXVkNIVT7isTwgD8DrGYA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:27:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aOXVkNIVT7isTwgD8DrGYA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:27:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aOXVkNIVT7isTwgD8DrGYA/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 02:27:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aOXVkNIVT7isTwgD8DrGYA/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 02:27:47 INFO - Downloaded 18089264 bytes. 02:27:47 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'] 02:27:47 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 02:27:51 INFO - Return code: 0 02:27: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')]} 02:27:51 INFO - https://queue.taskcluster.net/v1/task/aOXVkNIVT7isTwgD8DrGYA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 02:27:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aOXVkNIVT7isTwgD8DrGYA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:27:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aOXVkNIVT7isTwgD8DrGYA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:27:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aOXVkNIVT7isTwgD8DrGYA/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 02:27:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aOXVkNIVT7isTwgD8DrGYA/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 02:27:52 INFO - Downloaded 10840145 bytes. 02:27:52 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'] 02:27:52 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 02:27:52 INFO - Return code: 0 02:27:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:27:52 INFO - https://queue.taskcluster.net/v1/task/aOXVkNIVT7isTwgD8DrGYA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 02:27:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aOXVkNIVT7isTwgD8DrGYA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:27:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aOXVkNIVT7isTwgD8DrGYA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:27:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aOXVkNIVT7isTwgD8DrGYA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 02:27:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aOXVkNIVT7isTwgD8DrGYA/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 02:27:55 INFO - Downloaded 114210892 bytes. 02:27:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aOXVkNIVT7isTwgD8DrGYA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:27:55 INFO - mkdir: /builds/slave/test/properties 02:27:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:27:55 INFO - Writing to file /builds/slave/test/properties/build_url 02:27:55 INFO - Contents: 02:27:55 INFO - build_url:https://queue.taskcluster.net/v1/task/aOXVkNIVT7isTwgD8DrGYA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:27:56 INFO - Running post-action listener: _resource_record_post_action 02:27:56 INFO - Running post-action listener: set_extra_try_arguments 02:27:56 INFO - ##### 02:27:56 INFO - ##### Running populate-webroot step. 02:27:56 INFO - ##### 02:27:56 INFO - Running pre-action listener: _resource_record_pre_action 02:27:56 INFO - Running main action method: populate_webroot 02:27:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:27:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:27:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:27:56 INFO - '--tppagecycles', 02:27:56 INFO - '7'], 02:27:56 INFO - 'tests': ['tsvgm']}}, 02:27:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:27:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:27:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:27:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:27:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:27:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:27:56 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:27:56 INFO - 'talos_options': ['--disable-e10s'], 02:27:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:27:56 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:27:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:27:56 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:27:56 INFO - 'talos_options': ['--disable-e10s'], 02:27:56 INFO - 'tests': ['damp', 'tps']}, 02:27:56 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:27:56 INFO - 'tests': ['damp', 'tps']}, 02:27:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:27:56 INFO - 'tests': ['dromaeo_dom']}, 02:27:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:27:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:27:56 INFO - 'tests': ['a11yr', 02:27:56 INFO - 'ts_paint', 02:27:56 INFO - 'tpaint', 02:27:56 INFO - 'sessionrestore', 02:27:56 INFO - 'sessionrestore_no_auto_restore', 02:27:56 INFO - 'tabpaint']}, 02:27:56 INFO - 'other-e10s': {'tests': ['a11yr', 02:27:56 INFO - 'ts_paint', 02:27:56 INFO - 'tpaint', 02:27:56 INFO - 'sessionrestore', 02:27:56 INFO - 'sessionrestore_no_auto_restore', 02:27:56 INFO - 'tabpaint']}, 02:27:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:27:56 INFO - 'ts_paint', 02:27:56 INFO - 'tpaint', 02:27:56 INFO - 'sessionrestore', 02:27:56 INFO - 'sessionrestore_no_auto_restore', 02:27:56 INFO - 'tabpaint']}, 02:27:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:27:56 INFO - 'ts_paint', 02:27:56 INFO - 'tpaint', 02:27:56 INFO - 'sessionrestore', 02:27:56 INFO - 'sessionrestore_no_auto_restore', 02:27:56 INFO - 'tabpaint']}, 02:27:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:27:56 INFO - 'tests': ['a11yr', 02:27:56 INFO - 'ts_paint', 02:27:56 INFO - 'tpaint', 02:27:56 INFO - 'sessionrestore', 02:27:56 INFO - 'sessionrestore_no_auto_restore', 02:27:56 INFO - 'tabpaint']}, 02:27:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:27:56 INFO - 'tests': ['a11yr', 02:27:56 INFO - 'ts_paint', 02:27:56 INFO - 'tpaint', 02:27:56 INFO - 'sessionrestore', 02:27:56 INFO - 'sessionrestore_no_auto_restore', 02:27:56 INFO - 'tabpaint']}, 02:27:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:27:56 INFO - 'tests': ['tsvgx', 02:27:56 INFO - 'tsvgr_opacity', 02:27:56 INFO - 'tart', 02:27:56 INFO - 'tscrollx', 02:27:56 INFO - 'cart']}, 02:27:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:27:56 INFO - 'tsvgr_opacity', 02:27:56 INFO - 'tart', 02:27:56 INFO - 'tscrollx', 02:27:56 INFO - 'cart']}, 02:27:56 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:27:56 INFO - 'talos_options': ['--disable-e10s'], 02:27:56 INFO - 'tests': ['tp5o']}, 02:27:56 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:27:56 INFO - 'tests': ['tp5o']}, 02:27:56 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:27:56 INFO - 'talos_options': ['--disable-e10s', 02:27:56 INFO - '--xperf_path', 02:27:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:27:56 INFO - 'tests': ['tp5n']}, 02:27:56 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:27:56 INFO - 'talos_options': ['--xperf_path', 02:27:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:27:56 INFO - 'tests': ['tp5n']}}, 02:27:56 INFO - 'talos.zip': {'path': '', 02:27:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:27:56 INFO - Running post-action listener: _resource_record_post_action 02:27:56 INFO - ##### 02:27:56 INFO - ##### Running create-virtualenv step. 02:27:56 INFO - ##### 02:27:56 INFO - Running pre-action listener: _resource_record_pre_action 02:27:56 INFO - Running main action method: create_virtualenv 02:27:56 INFO - Creating virtualenv /builds/slave/test/build/venv 02:27:56 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 02:27:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:27:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:27:56 INFO - Using real prefix '/tools/python27' 02:27:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:27:57 INFO - Installing distribute.............................................................................................................................................................................................done. 02:28:00 INFO - Installing pip.................done. 02:28:00 INFO - Return code: 0 02:28:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:28:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:28:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:28:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:00 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 0x10c2a8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2b8420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe111cc7190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c147e00>, '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 0x10bfb0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c142b20>, '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.dVULbzvWip/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.xEQdmiXgSU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 02:28:00 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 02:28:00 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 02:28:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dVULbzvWip/Render', 02:28:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:00 INFO - 'HOME': '/Users/cltbld', 02:28:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:00 INFO - 'LOGNAME': 'cltbld', 02:28:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:00 INFO - 'MOZ_NO_REMOTE': '1', 02:28:00 INFO - 'NO_EM_RESTART': '1', 02:28:00 INFO - 'PAGER': '/bin/cat', 02:28:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:00 INFO - 'PWD': '/builds/slave/test', 02:28:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:00 INFO - 'SHELL': '/bin/bash', 02:28:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xEQdmiXgSU/Listeners', 02:28:00 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 02:28:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:00 INFO - 'USER': 'cltbld', 02:28:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:00 INFO - 'XPC_FLAGS': '0x0', 02:28:00 INFO - 'XPC_SERVICE_NAME': '0', 02:28:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:00 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 02:28:00 INFO - Cleaning up... 02:28:00 INFO - Return code: 0 02:28:00 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 02:28:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:28:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:28:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:00 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 0x10c2a8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2b8420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe111cc7190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c147e00>, '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 0x10bfb0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c142b20>, '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.dVULbzvWip/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.xEQdmiXgSU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 02:28:00 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 02:28:00 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 02:28:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dVULbzvWip/Render', 02:28:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:00 INFO - 'HOME': '/Users/cltbld', 02:28:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:00 INFO - 'LOGNAME': 'cltbld', 02:28:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:00 INFO - 'MOZ_NO_REMOTE': '1', 02:28:00 INFO - 'NO_EM_RESTART': '1', 02:28:00 INFO - 'PAGER': '/bin/cat', 02:28:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:00 INFO - 'PWD': '/builds/slave/test', 02:28:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:00 INFO - 'SHELL': '/bin/bash', 02:28:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xEQdmiXgSU/Listeners', 02:28:00 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 02:28:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:00 INFO - 'USER': 'cltbld', 02:28:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:00 INFO - 'XPC_FLAGS': '0x0', 02:28:00 INFO - 'XPC_SERVICE_NAME': '0', 02:28:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:00 INFO - Downloading/unpacking psutil>=0.7.1 02:28:00 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 02:28:00 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 02:28:00 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 02:28:00 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 02:28:00 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 02:28:00 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 02:28:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:28:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:28:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:28:03 INFO - Installing collected packages: psutil 02:28:03 INFO - Running setup.py install for psutil 02:28:03 INFO - building 'psutil._psutil_osx' extension 02:28:03 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 02:28:03 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 02:28:03 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 02:28:04 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 02:28:04 INFO - building 'psutil._psutil_posix' extension 02:28:04 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 02:28:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:28:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:28:04 INFO - ^ 02:28:04 INFO - 1 warning generated. 02:28:04 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 02:28:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:28:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:28:04 INFO - Successfully installed psutil 02:28:04 INFO - Cleaning up... 02:28:04 INFO - Return code: 0 02:28:04 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 02:28:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:28:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:28:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:04 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 0x10c2a8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2b8420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe111cc7190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c147e00>, '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 0x10bfb0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c142b20>, '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.dVULbzvWip/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.xEQdmiXgSU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 02:28:04 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 02:28:04 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 02:28:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dVULbzvWip/Render', 02:28:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:04 INFO - 'HOME': '/Users/cltbld', 02:28:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:04 INFO - 'LOGNAME': 'cltbld', 02:28:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:04 INFO - 'MOZ_NO_REMOTE': '1', 02:28:04 INFO - 'NO_EM_RESTART': '1', 02:28:04 INFO - 'PAGER': '/bin/cat', 02:28:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:04 INFO - 'PWD': '/builds/slave/test', 02:28:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:04 INFO - 'SHELL': '/bin/bash', 02:28:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xEQdmiXgSU/Listeners', 02:28:04 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 02:28:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:04 INFO - 'USER': 'cltbld', 02:28:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:04 INFO - 'XPC_FLAGS': '0x0', 02:28:04 INFO - 'XPC_SERVICE_NAME': '0', 02:28:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:04 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 02:28:04 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 02:28:04 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 02:28:04 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 02:28:04 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 02:28:04 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 02:28:04 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 02:28:07 INFO - Downloading mozsystemmonitor-0.0.tar.gz 02:28:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:28:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 02:28:07 INFO - Installing collected packages: mozsystemmonitor 02:28:07 INFO - Running setup.py install for mozsystemmonitor 02:28:07 INFO - Successfully installed mozsystemmonitor 02:28:07 INFO - Cleaning up... 02:28:07 INFO - Return code: 0 02:28:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:28:07 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')]} 02:28:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:07 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')]} 02:28:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:07 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 0x10c2a8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2b8420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe111cc7190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c147e00>, '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 0x10bfb0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c142b20>, '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.dVULbzvWip/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.xEQdmiXgSU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 02:28:07 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 02:28:07 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 02:28:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dVULbzvWip/Render', 02:28:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:07 INFO - 'HOME': '/Users/cltbld', 02:28:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:07 INFO - 'LOGNAME': 'cltbld', 02:28:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:07 INFO - 'MOZ_NO_REMOTE': '1', 02:28:07 INFO - 'NO_EM_RESTART': '1', 02:28:07 INFO - 'PAGER': '/bin/cat', 02:28:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:07 INFO - 'PWD': '/builds/slave/test', 02:28:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:07 INFO - 'SHELL': '/bin/bash', 02:28:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xEQdmiXgSU/Listeners', 02:28:07 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 02:28:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:07 INFO - 'USER': 'cltbld', 02:28:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:07 INFO - 'XPC_FLAGS': '0x0', 02:28:07 INFO - 'XPC_SERVICE_NAME': '0', 02:28:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:07 INFO - Downloading/unpacking blobuploader==1.2.4 02:28:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:10 INFO - Downloading blobuploader-1.2.4.tar.gz 02:28:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:28:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:28: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 02:28: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 02:28: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 02:28: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 02:28: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 02:28: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 02:28:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:28:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:28: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 02:28: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 02:28: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 02:28: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 02:28: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 02:28: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 02:28:11 INFO - Downloading docopt-0.6.1.tar.gz 02:28:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:28:11 INFO - Installing collected packages: blobuploader, requests, docopt 02:28:11 INFO - Running setup.py install for blobuploader 02:28:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:28:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:28:11 INFO - Running setup.py install for requests 02:28:11 INFO - Running setup.py install for docopt 02:28:12 INFO - Successfully installed blobuploader requests docopt 02:28:12 INFO - Cleaning up... 02:28:12 INFO - Return code: 0 02:28:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:28:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:28:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:28:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:12 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 0x10c2a8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2b8420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe111cc7190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c147e00>, '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 0x10bfb0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c142b20>, '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.dVULbzvWip/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.xEQdmiXgSU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 02:28:12 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 02:28:12 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 02:28:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dVULbzvWip/Render', 02:28:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:12 INFO - 'HOME': '/Users/cltbld', 02:28:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:12 INFO - 'LOGNAME': 'cltbld', 02:28:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:12 INFO - 'MOZ_NO_REMOTE': '1', 02:28:12 INFO - 'NO_EM_RESTART': '1', 02:28:12 INFO - 'PAGER': '/bin/cat', 02:28:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:12 INFO - 'PWD': '/builds/slave/test', 02:28:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:12 INFO - 'SHELL': '/bin/bash', 02:28:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xEQdmiXgSU/Listeners', 02:28:12 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 02:28:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:12 INFO - 'USER': 'cltbld', 02:28:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:12 INFO - 'XPC_FLAGS': '0x0', 02:28:12 INFO - 'XPC_SERVICE_NAME': '0', 02:28:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:28:12 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-wOFxnM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:28:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:28:12 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-DZm3o4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:28:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:28:12 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-oD9l9r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:28:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:28:12 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-Ia2XoE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:28:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:28:12 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-W7OgMP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:28:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:28:12 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-POiY7n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:28:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:28:13 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-7_H4jq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:28:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:28:13 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-kTMFgn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:28:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:28:13 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-xjZpwX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:28:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:28:13 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-5N1vTX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:28:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:28:13 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-SUk5Vz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:28:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:28:13 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-TGIbqq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:28:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:28:13 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-l6ry5z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:28:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:28:13 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-vy4WJV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:28:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:28:13 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-3gCuHL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:28:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:28:14 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-qajXDu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:28:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:28:14 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-VbjGGy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:28:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:28:14 INFO - Running setup.py install for manifestparser 02:28:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:28:14 INFO - Running setup.py install for mozcrash 02:28:14 INFO - Running setup.py install for mozdebug 02:28:14 INFO - Running setup.py install for mozdevice 02:28:14 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:28:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:28:14 INFO - Running setup.py install for mozfile 02:28:14 INFO - Running setup.py install for mozhttpd 02:28:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:28:15 INFO - Running setup.py install for mozinfo 02:28:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:28:15 INFO - Running setup.py install for mozInstall 02:28:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:28:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:28:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:28:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:28:15 INFO - Running setup.py install for mozleak 02:28:15 INFO - Running setup.py install for mozlog 02:28:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:28:15 INFO - Running setup.py install for moznetwork 02:28:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:28:15 INFO - Running setup.py install for mozprocess 02:28:15 INFO - Running setup.py install for mozprofile 02:28:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:28:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:28:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:28:16 INFO - Running setup.py install for mozrunner 02:28:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:28:16 INFO - Running setup.py install for mozscreenshot 02:28:16 INFO - Running setup.py install for moztest 02:28:16 INFO - Running setup.py install for mozversion 02:28:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:28:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:28:16 INFO - Cleaning up... 02:28:16 INFO - Return code: 0 02:28:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:28:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:28:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:28:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:16 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 0x10c2a8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2b8420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe111cc7190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c147e00>, '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 0x10bfb0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c142b20>, '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.dVULbzvWip/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.xEQdmiXgSU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 02:28:16 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 02:28:16 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 02:28:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dVULbzvWip/Render', 02:28:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:16 INFO - 'HOME': '/Users/cltbld', 02:28:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:16 INFO - 'LOGNAME': 'cltbld', 02:28:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:16 INFO - 'MOZ_NO_REMOTE': '1', 02:28:16 INFO - 'NO_EM_RESTART': '1', 02:28:16 INFO - 'PAGER': '/bin/cat', 02:28:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:16 INFO - 'PWD': '/builds/slave/test', 02:28:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:16 INFO - 'SHELL': '/bin/bash', 02:28:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xEQdmiXgSU/Listeners', 02:28:16 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 02:28:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:16 INFO - 'USER': 'cltbld', 02:28:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:16 INFO - 'XPC_FLAGS': '0x0', 02:28:16 INFO - 'XPC_SERVICE_NAME': '0', 02:28:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:28:17 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-JMvUVu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:28:17 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)) 02:28:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:28:17 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-nbJamm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:28:17 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)) 02:28:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:28:17 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-VHVUCU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:28:17 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)) 02:28:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:28:17 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-T9oo_H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:28:17 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)) 02:28:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:28:17 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-wzO542-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:28:17 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)) 02:28:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:28:17 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-UDq9lG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:28:17 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)) 02:28:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:28:17 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-i9r4I7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:28:17 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)) 02:28:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:28:17 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-9SMm8y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:28:17 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)) 02:28:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:28:17 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-eCENds-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:28:17 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)) 02:28:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:28:18 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-HGyBuc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:28:18 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)) 02:28:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:28:18 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-i5DOi4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:28:18 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)) 02:28:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:28:18 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-sjhZa5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:28:18 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)) 02:28:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:28:18 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-t3O4ze-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:28:18 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)) 02:28:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:28:18 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-teK5S9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:28:18 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)) 02:28:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:28:18 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-0B5mB4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:28:18 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)) 02:28:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:28:18 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-wbbaLD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:28:18 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)) 02:28:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:28:18 INFO - Running setup.py (path:/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/pip-XFoedq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:28:18 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)) 02:28:18 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)) 02:28:18 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)) 02:28:18 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)) 02:28:18 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)) 02:28:18 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)) 02:28:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:28:18 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 02:28:18 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 02:28:18 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 02:28:18 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 02:28:18 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 02:28:18 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 02:28:21 INFO - Downloading blessings-1.6.tar.gz 02:28:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:28:21 INFO - Installing collected packages: blessings 02:28:21 INFO - Running setup.py install for blessings 02:28:21 INFO - Successfully installed blessings 02:28:21 INFO - Cleaning up... 02:28:21 INFO - Return code: 0 02:28:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:28:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:28:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:28:21 INFO - Reading from file tmpfile_stdout 02:28:21 INFO - Current package versions: 02:28:21 INFO - blessings == 1.6 02:28:21 INFO - blobuploader == 1.2.4 02:28:21 INFO - docopt == 0.6.1 02:28:21 INFO - manifestparser == 1.1 02:28:21 INFO - mozInstall == 1.12 02:28:21 INFO - mozcrash == 0.17 02:28:21 INFO - mozdebug == 0.1 02:28:21 INFO - mozdevice == 0.48 02:28:21 INFO - mozfile == 1.2 02:28:21 INFO - mozhttpd == 0.7 02:28:21 INFO - mozinfo == 0.9 02:28:21 INFO - mozleak == 0.1 02:28:21 INFO - mozlog == 3.1 02:28:21 INFO - moznetwork == 0.27 02:28:21 INFO - mozprocess == 0.22 02:28:21 INFO - mozprofile == 0.28 02:28:21 INFO - mozrunner == 6.11 02:28:21 INFO - mozscreenshot == 0.1 02:28:21 INFO - mozsystemmonitor == 0.0 02:28:21 INFO - moztest == 0.7 02:28:21 INFO - mozversion == 1.4 02:28:21 INFO - psutil == 3.1.1 02:28:21 INFO - requests == 1.2.3 02:28:21 INFO - wsgiref == 0.1.2 02:28:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:28:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:28:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:28:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:21 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 0x10c2a8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2b8420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe111cc7190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c147e00>, '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 0x10bfb0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c142b20>, '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.dVULbzvWip/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.xEQdmiXgSU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 02:28:21 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 02:28:21 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 02:28:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dVULbzvWip/Render', 02:28:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:21 INFO - 'HOME': '/Users/cltbld', 02:28:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:21 INFO - 'LOGNAME': 'cltbld', 02:28:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:21 INFO - 'MOZ_NO_REMOTE': '1', 02:28:21 INFO - 'NO_EM_RESTART': '1', 02:28:21 INFO - 'PAGER': '/bin/cat', 02:28:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:21 INFO - 'PWD': '/builds/slave/test', 02:28:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:21 INFO - 'SHELL': '/bin/bash', 02:28:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xEQdmiXgSU/Listeners', 02:28:21 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 02:28:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:21 INFO - 'USER': 'cltbld', 02:28:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:21 INFO - 'XPC_FLAGS': '0x0', 02:28:21 INFO - 'XPC_SERVICE_NAME': '0', 02:28:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:22 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)) 02:28:22 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)) 02:28:22 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)) 02:28:22 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)) 02:28:22 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)) 02:28:22 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)) 02:28:22 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)) 02:28:22 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)) 02:28:22 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)) 02:28:22 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)) 02:28:22 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)) 02:28:22 INFO - Cleaning up... 02:28:22 INFO - Return code: 0 02:28:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:28:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:28:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:28:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:22 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 0x10c2a8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2b8420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe111cc7190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c147e00>, '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 0x10bfb0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c142b20>, '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.dVULbzvWip/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.xEQdmiXgSU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 02:28:22 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 02:28:22 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 02:28:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dVULbzvWip/Render', 02:28:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:22 INFO - 'HOME': '/Users/cltbld', 02:28:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:22 INFO - 'LOGNAME': 'cltbld', 02:28:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:22 INFO - 'MOZ_NO_REMOTE': '1', 02:28:22 INFO - 'NO_EM_RESTART': '1', 02:28:22 INFO - 'PAGER': '/bin/cat', 02:28:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:22 INFO - 'PWD': '/builds/slave/test', 02:28:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:22 INFO - 'SHELL': '/bin/bash', 02:28:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xEQdmiXgSU/Listeners', 02:28:22 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 02:28:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:22 INFO - 'USER': 'cltbld', 02:28:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:22 INFO - 'XPC_FLAGS': '0x0', 02:28:22 INFO - 'XPC_SERVICE_NAME': '0', 02:28:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:22 INFO - Downloading/unpacking jsonschema 02:28:22 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 02:28:22 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 02:28:22 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 02:28:22 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 02:28:22 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 02:28:22 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 02:28:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:28:24 INFO - Downloading/unpacking functools32 (from jsonschema) 02:28:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:25 INFO - Downloading functools32-3.2.3-2.tar.gz 02:28:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:28:25 INFO - warning: no files found matching '*.txt' 02:28:25 INFO - no previously-included directories found matching 'build' 02:28:25 INFO - no previously-included directories found matching 'dist' 02:28:25 INFO - no previously-included directories found matching '.git*' 02:28:25 INFO - Installing collected packages: jsonschema, functools32 02:28:25 INFO - Running setup.py install for functools32 02:28:25 INFO - warning: no files found matching '*.txt' 02:28:25 INFO - no previously-included directories found matching 'build' 02:28:25 INFO - no previously-included directories found matching 'dist' 02:28:25 INFO - no previously-included directories found matching '.git*' 02:28:25 INFO - Successfully installed jsonschema functools32 02:28:25 INFO - Cleaning up... 02:28:25 INFO - Return code: 0 02:28:25 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 02:28:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:28:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:28:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:26 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')]} 02:28:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:28:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:28:26 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 0x10c2a8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c2b8420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe111cc7190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c147e00>, '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 0x10bfb0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c142b20>, '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.dVULbzvWip/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.xEQdmiXgSU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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 02:28:26 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 02:28:26 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 02:28:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dVULbzvWip/Render', 02:28:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:28:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:28:26 INFO - 'HOME': '/Users/cltbld', 02:28:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:28:26 INFO - 'LOGNAME': 'cltbld', 02:28:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:28:26 INFO - 'MOZ_NO_REMOTE': '1', 02:28:26 INFO - 'NO_EM_RESTART': '1', 02:28:26 INFO - 'PAGER': '/bin/cat', 02:28:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:28:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:28:26 INFO - 'PWD': '/builds/slave/test', 02:28:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:28:26 INFO - 'SHELL': '/bin/bash', 02:28:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xEQdmiXgSU/Listeners', 02:28:26 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 02:28:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:28:26 INFO - 'USER': 'cltbld', 02:28:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:28:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:28:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:28:26 INFO - 'XPC_FLAGS': '0x0', 02:28:26 INFO - 'XPC_SERVICE_NAME': '0', 02:28:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:28:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:28:26 INFO - Downloading/unpacking flake8 02:28:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:28:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:28:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:28:28 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 02:28:28 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 02:28:28 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 02:28:28 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 02:28:28 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 02:28:28 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 02:28:28 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 02:28:28 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 02:28:29 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 02:28:29 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 02:28:29 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 02:28:29 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 02:28:29 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 02:28:29 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 02:28:29 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 02:28:29 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 02:28:29 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 02:28:29 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 02:28:29 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 02:28:29 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 02:28:29 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 02:28:29 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 02:28:29 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 02:28:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 02:28:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:28:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:28:29 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:28:29 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:28:29 INFO - no previously-included directories found matching 'docs/_build' 02:28:29 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 02:28:30 INFO - Running setup.py install for pep8 02:28:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:28:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:28:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:28:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:28:30 INFO - no previously-included directories found matching 'docs/_build' 02:28:30 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 02:28:30 INFO - Successfully installed flake8 mccabe pyflakes pep8 02:28:30 INFO - Cleaning up... 02:28:30 INFO - Return code: 0 02:28:30 INFO - Running post-action listener: _resource_record_post_action 02:28:30 INFO - Running post-action listener: _start_resource_monitoring 02:28:30 INFO - Starting resource monitoring. 02:28:30 INFO - ##### 02:28:30 INFO - ##### Running install step. 02:28:30 INFO - ##### 02:28:30 INFO - Running pre-action listener: _resource_record_pre_action 02:28:30 INFO - Running main action method: install 02:28:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:28:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:28:30 INFO - Reading from file tmpfile_stdout 02:28:30 INFO - Detecting whether we're running mozinstall >=1.0... 02:28:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:28:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:28:30 INFO - Reading from file tmpfile_stdout 02:28:30 INFO - Output received: 02:28:30 INFO - Usage: mozinstall [options] installer 02:28:30 INFO - Options: 02:28:30 INFO - -h, --help show this help message and exit 02:28:30 INFO - -d DEST, --destination=DEST 02:28:30 INFO - Directory to install application into. [default: 02:28:30 INFO - "/builds/slave/test"] 02:28:30 INFO - --app=APP Application being installed. [default: firefox] 02:28:30 INFO - mkdir: /builds/slave/test/build/application 02:28:30 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'] 02:28:30 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 02:29:02 INFO - Reading from file tmpfile_stdout 02:29:02 INFO - Output received: 02:29:02 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:29:02 INFO - Running post-action listener: _resource_record_post_action 02:29:02 INFO - ##### 02:29:02 INFO - ##### Running run-tests step. 02:29:02 INFO - ##### 02:29:02 INFO - Running pre-action listener: _resource_record_pre_action 02:29:02 INFO - Running main action method: run_tests 02:29:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:29:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:29:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:29:02 INFO - 2016-05-10 02:29:02.522 screenresolution[1915:12163] starting screenresolution argv=screenresolution get 02:29:02 INFO - 2016-05-10 02:29:02.525 screenresolution[1915:12163] Display 0: 1600x1200x32@60 02:29:02 INFO - 2016-05-10 02:29:02.531 screenresolution[1916:12165] starting screenresolution argv=screenresolution list 02:29:02 INFO - Available Modes on Display 0 02:29:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:29:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:29:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:29:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:29:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:29:02 INFO - Intel Iris: 02:29:02 INFO - Chipset Model: Intel Iris 02:29:02 INFO - Type: GPU 02:29:02 INFO - Bus: Built-In 02:29:02 INFO - VRAM (Dynamic, Max): 1536 MB 02:29:02 INFO - Vendor: Intel (0x8086) 02:29:02 INFO - Device ID: 0x0a2e 02:29:02 INFO - Revision ID: 0x0009 02:29:02 INFO - Displays: 02:29:02 INFO - Display: 02:29:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:29:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:29:02 INFO - Display Serial Number: 1600x1200 60 02:29:02 INFO - Main Display: Yes 02:29:02 INFO - Mirror: Off 02:29:02 INFO - Online: Yes 02:29:02 INFO - Rotation: Supported 02:29:02 INFO - Return code: 0 02:29:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:29:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:29:02 INFO - Python 2.7.3 02:29:02 INFO - Return code: 0 02:29:02 INFO - grabbing minidump binary from tooltool 02:29:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:29:02 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c147e00>, '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 0x10bfb0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c142b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:29:02 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 02:29:02 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 02:29:02 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:29:02 INFO - Return code: 0 02:29:02 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:29:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:29:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:29:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:29:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:29:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 02:29:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 02:29:03 INFO - Return code: 0 02:29:03 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-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aOXVkNIVT7isTwgD8DrGYA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0112', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 02:29:03 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-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/aOXVkNIVT7isTwgD8DrGYA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0112 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 02:29:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dVULbzvWip/Render', 02:29:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:29:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:29:03 INFO - 'HOME': '/Users/cltbld', 02:29:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:29:03 INFO - 'LOGNAME': 'cltbld', 02:29:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:29:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:29:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:29:03 INFO - 'MOZ_NO_REMOTE': '1', 02:29:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:29:03 INFO - 'NO_EM_RESTART': '1', 02:29:03 INFO - 'PAGER': '/bin/cat', 02:29:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:29:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:29:03 INFO - 'PWD': '/builds/slave/test', 02:29:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:29:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:29:03 INFO - 'SHELL': '/bin/bash', 02:29:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xEQdmiXgSU/Listeners', 02:29:03 INFO - 'TMPDIR': '/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/', 02:29:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:29:03 INFO - 'USER': 'cltbld', 02:29:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:29:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:29:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:29:03 INFO - 'XPC_FLAGS': '0x0', 02:29:03 INFO - 'XPC_SERVICE_NAME': '0', 02:29:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:29:03 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aOXVkNIVT7isTwgD8DrGYA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0112', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 02:29:04 INFO - mozversion application_buildid: 20160510004007 02:29:04 INFO - mozversion application_changeset: 63b7cc21ae7cf57d72b1a3a1b169ba3ea9fc237d 02:29:04 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:29:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:29:04 INFO - mozversion application_name: Firefox 02:29:04 INFO - mozversion application_remotingname: firefox-dev 02:29:04 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:29:04 INFO - mozversion application_vendor: Mozilla 02:29:04 INFO - mozversion application_version: 48.0a2 02:29:04 INFO - mozversion platform_buildid: 20160510004007 02:29:04 INFO - mozversion platform_changeset: 63b7cc21ae7cf57d72b1a3a1b169ba3ea9fc237d 02:29:04 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:29:04 INFO - mozversion platform_version: 48.0a2 02:29:04 INFO - using testdate: 1462872544 02:29:04 INFO - actual date: 1462872544 02:29:04 INFO - starting webserver on 'localhost:50922' 02:29:04 INFO - SUITE-START | Running 2 tests 02:29:04 INFO - TEST-START | dromaeo_css 02:29:04 INFO - operating with platform_type : mac_ 02:29:04 INFO - Initialising browser for dromaeo_css test... 02:29:04 INFO - TEST-INFO | started process 1934 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/tmpKNVj4a/profile http://localhost:50922/getInfo.html) 02:29:07 INFO - PROCESS | 1934 | __metrics Screen width/height:1600/1200 02:29:07 INFO - PROCESS | 1934 | colorDepth:24 02:29:07 INFO - PROCESS | 1934 | Browser inner width/height: 1024/689 02:29:07 INFO - PROCESS | 1934 | __metrics 02:29:07 ERROR - PROCESS | 1934 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 02:29:07 INFO - TEST-INFO | 1934: exit 0 02:29:07 INFO - Browser initialized. 02:29:07 INFO - Running cycle 1/1 for dromaeo_css test... 02:29:07 INFO - TEST-INFO | started process 1938 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/tmpKNVj4a/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:29:19 INFO - PROCESS | 1938 | RSS: Main: 220536832 02:29:19 INFO - PROCESS | 1938 | 02:30:09 INFO - PROCESS | 1938 | 1462872609359 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] 02:32:20 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:50922/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50922/tests/dromaeo/cssquery-ext.html) 02:32:21 INFO - PROCESS | 1938 | RSS: Main: 231342080 02:32:21 INFO - PROCESS | 1938 | 02:35:24 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:50922/tests/dromaeo/cssquery-ext.html (next: http://localhost:50922/tests/dromaeo/cssquery-jquery.html) 02:35:24 INFO - PROCESS | 1938 | RSS: Main: 242860032 02:35:24 INFO - PROCESS | 1938 | 02:38:26 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:50922/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50922/tests/dromaeo/cssquery-mootools.html) 02:38:26 INFO - PROCESS | 1938 | RSS: Main: 244703232 02:38:26 INFO - PROCESS | 1938 | 02:41:28 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:50922/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50922/tests/dromaeo/cssquery-prototype.html) 02:41:29 INFO - PROCESS | 1938 | RSS: Main: 246247424 02:41:29 INFO - PROCESS | 1938 | 02:44:31 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:50922/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50922/tests/dromaeo/cssquery-yui.html) 02:44:32 INFO - PROCESS | 1938 | RSS: Main: 250351616 02:44:32 INFO - PROCESS | 1938 | 02:47:34 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:50922/tests/dromaeo/cssquery-yui.html (next: http://localhost:50922/tests/dromaeo/cssquery-dojo.html) 02:47:34 INFO - PROCESS | 1938 | RSS: Main: 250560512 02:47:34 INFO - PROCESS | 1938 | 02:47:34 INFO - PROCESS | 1938 | __start_tp_report 02:47:34 INFO - PROCESS | 1938 | _x_x_mozilla_page_load 02:47:34 INFO - PROCESS | 1938 | _x_x_mozilla_page_load_details 02:47:34 INFO - PROCESS | 1938 | |i|pagename|runs| 02:47:34 INFO - PROCESS | 1938 | |0;dojo.html;3426.657334266573;3500.8949731508064;3516.9800757780245;3547.1841476460413;3555.964440355596;5636.436356364372;5841.299044114701;5859.941400585998;5881.411858814122;5900.763969441217;5784.1902133701315;5833.79581714639;5850.063989761639;5854.824355269338;5864.677442740647;5316.229146773716;5323.680579165243;5384.3807962084275;5395.649282796625;5401.297831281928;5254.737263136847;5380.757865896044;5389.245505629215;5408.702521361323;5410.053240682884;5316.840494785143;5318.547923426513;5341.385740639818;5341.385740639837;5359.9732001339935;5299.072662284123;5324.946750532484;5331.5201631853315;5334.03987282289;5362.624616276862;4911.557959783637;5186.5850731941355;5389.245505629215;5428.484293992104;5451.563874889999;5341.43914888935;5696.971515142398;5843.386444423317;5890.97054514729;5925.851853703692;6098.8780224395705;6189.412005859436;6285.685715714196;6286.402791734778;6309.274433440167;5202.557782588485;5780.479756821906;5844.1818145459665;6314.179156709644;6324.778632747878;5387.676739395571;5911.438413350725;5969.104634304846;5973.074173503114;5981.043033114681;6258.906116408258;6276.905846412307;6327.620342779448;6399.776007839796;6431.5819471734185;6241.469475094668;6249.531285153632;6268.27914789805;6282.246130464282;6291.811245662638;6313.55805093639;6313.77901773445;6328.11406403104;6333.714982825784;6342.270638876496;6133.29467111279;6187.319394866561;6271;6274.905876411857;6303.054541818764;6255.374462553708;6280.90084235257;6307.306196318493;6312.8421789454715;6344.714487848058;4329.805158767863;5011.072951503984;5012.749362531858;5021.874453138701;5027.069992051483;4369.213541562485;4417.249067658506;4456.197884380843;4460.464744230713;4535.727856328631;4880.975595122072;5848.678322692293;5867.501262392659;5883.499902508334;5911.379305172981;6216.129741836146;6263.655498947511;6298.0552917062805;6301.96849015752;6316.842078948063;4857.975710121426;5713.885722285531;5760.596758226968;5862.618929769551;5989.940100598938;5451.591130665216;5474.370447398521;5487;5488.984537860509;5500.119980803052;6511.706973186123;6522.988936714817;6527.477801775752;6536.444402225864;6536.575122617015;6184.628922264678;6261.4364707174755;6315.242170939517;6330.208723909512;6350.650714210578;6484.935150648622;6529.216494020748;6535.836604084746;6563.606183628997;6577.736890524517;6049.727762250621;6277.340879207617;6297.1813664223355;6304.905426418699;6312.116303717575;6214.844628884314;6269.467095296768;6327.209098862642;6329.968350158219;6358.237011558643;5857.502112320501;6195.752169913333;6232.875342493034;6242.157308763441;6260.154879091447;6080.543959203166;6145.201123853871;6213.627182369072;6220.626762394271;6223.37766223374;6040.184575082308;6058.4244496772735;6114.145302971568;6176.289726681489;6184.721687523982;6227.501799856092;6265.655388953652;6271.592346497463;6304;6307.96846015767;5972.820815375546;6069.908951365821;6110.953680641429;6142.662153581596;6187.412195841388 02:47:34 INFO - PROCESS | 1938 | |1;ext.html;479.92561153021273;528.912729399649;553.8532288943428;586.9815869466474;626.5457543281121;38871.22257554847;38880.805595972015;38889.41665875014;39815.2036959261;39863.40204896929;37117.70088046912;37164;40484;41000.794996024975;41007.56473523427;9393.342466027363;9591.712248632552;9614.855777163348;9656.372335798185;9669.758256043584;9495.762605934839;9605.519724013806;9616.759581010461;9687.225021998223;9718.173955213815;9622.230221582256;9652.420854748703;9711.902880971205;9712.028797120302;9765.90234097657;9580.520973951345;9615;9652.469114198717;9712.22302215821;9755.560999755027;9573.28200384974;9743.65897193602;9754.975727548646;9756.512174391322;9780.853287200698;32532.6746732532;39937.600623993676;40111.7994410029;40341.193176136316;40483;2718.9320266993286;3048.969510304913;3156.4002839460427;3165.129589362921;3197.7921435106878;28739.42521149586;28788.424231515462;28826.135215943556;29790.851045744632;29892.55161172584;2603.2060221632396;2759.310172456886;2819.407924335892;2879.4816932952062;2889.6965818589165;292889;356232;357758.2112089449;358306;358328;36212.818935905154;36806.44790328147;60943.6952815242;61199;61506.692466538276;30383.848080759453;35699.286014280086;35780.64219357773;35900.92297231087;35975.820120899225;15338.926275160855;15668.373265069498;15772.05367677943;15918.283677234553;15922.76115858263;11076;13012.154209976321;13296.202227866359;13326.666833329244;13341.066125371131;6987.336203060701;8021.5187088774865;8159.877601835978;8265.83468330642;8341.582920853933;2794.0081271148856;3395.6434574369337;3453.1367158210446;3478.6869181773254;3512.5433693619666;949.9335046546813;962.9133377996014;1063.9202059845543;1112.9554817807198;1142.2289954280827;27099.86450067737;30641.38717225643;32463.350732985207;33001.50497742536;33360.83319583387;1040.8594839696543;1137.863456385239;1148.4430051425052;1166.276908316849;1191.1780871198846;11774.608044305409;15074.820982763675;15157.469488567847;15232.695346093016;15264.160471174193;625726;637290;645914;646895;650829;58646.41353586495;60418.09372859323;60520;60557;60598.69700651557;1489.977650335246;1558.057375287957;1572;1574.1106274954614;1574.9842501574838;28776.568351474325;29524.409511809215;29669.406611868075;29881.850590746908;30165;53022.73488632532;53703.73148134234;53955;54063.45936540584;54094.729526352115;596883;605754;612747;612772;613221;42781.50264740719;43013.56986430221;44148.23407063681;44258.77870610626;44807;47079.293810592186;47181.76409117933;48189;48371.27443088286;48919.75540122276;35394.93815185549;38846.805765972116;39157.216855662155;39659.405108923966;39857;31820;38092;38484.230315392975;38783.612163878;39444.60555394524;38124.618753813214;38626.42060369041;38669.806650967694;39091.804540977115;39157.412638809874;33448.498272525445;33929;34295.828520857234;34328.48507272443;34522.30955380928;21850.672239916097;25745.227643170736;25831.870840645675;25969.740302596732;26020.21939341823 02:47:34 INFO - PROCESS | 1938 | |2;jquery.html;379.2869405517627;383.31004192453594;383.57614835606654;387.6821006774445;396.4647725570482;19598;20708.792912070876;20855.791442085574;21024.264150754727;21101.68347474789;20832.687509687366;21134.577308453823;21623.56752864942;21628.810415427186;21976.89011554944;4386.649068074546;5858.209141765849;6099.695015249242;6123.326434092269;6131.294901086388;3178.634457037436;5612.971935140318;5691.601587888849;5701.116326969326;5812.1281807728965;4834.9033019339595;5766.769329226826;5806.912896306538;5812.273465816773;5825.475707186352;5592.245046918675;5775.971120144393;6349.8412539686415;6436;6466.353364663543;5635.8591035224035;5649.4633009864;5667.574931880125;5679.2333035040565;5687.317521897357;19979.001049947445;22814.08743650252;24025.27924162278;24130.879345603335;24259.393515162086;1759.6304775997055;2131.765505794361;2164.426426996847;2230.553889222162;2243.4839986802963;9193.954030229874;9240.907590924138;9541.33210675253;9683.90316096837;12483.875161248363;1776.2184638759034;1780.866435017379;1787.8647059117425;1788.5886246163325;1801.2794882047156;107893;111056.75879384446;118914;119255;119261.40369298185;10782.245242833083;10882.129429645609;11065.944670276596;11401.657950261506;11506.964373206452;12908.483660653657;13495;13560.98292628037;13614.251216183206;13691.246981416118;8610.225079742853;8652.653893844175;8666.090060543678;8791.208791208694;8850.955745221361;7872.685092596346;9124.49815260167;9132.360734748503;9138.268938484905;9155.22180614642;4281.828726850891;4303.096349766522;4367.737935723867;4386.210482113251;4452.888677783017;1750.4661078371219;1812.0935601518652;1818.5271829324472;1856.851451883846;1885.151681743221;820.7660816667341;944.0700909604035;959.3428501476511;963.9614415423305;984.9852252216223;19354.516137096685;20238.19047238094;20276.695849562268;20359.796402036085;20528.486787830425;339.45347989736507;969.2439896880445;984.3208186351395;991.3704797453669;1030.1912998296302;5814.680192589448;5945.821625351246;5985.760569577168;5989.55078369124;5996;265598;267014;617482.8251717426;620789;621335;125943;129770;130370.34814825865;130601;132282;430.70281505760926;441.97348159110555;443.98890027749565;444.1095603315332;445.56557356577076;8780.78048048804;9769.902300976899;10003.799924001625;10646.574137034431;10726.946365268435;51916;53382.73308633432;53652;53981.19028214501;54926.45073549214;74355;76356.12794848491;77221.45557088715;78248.60875695584;80531.79202312087;7323.414126869944;7460.477766556289;7979.72070977513;8063.153368896182;8109.351251899716;12557.121001529806;12589.685257868627;12654.620361389174;12733.55432559856;12932.224066556037;10800.783984320427;10981.341119532854;11335.263207891461;11368.431578421212;11415.37215453158;8757.649694012169;10847.186461015614;10995;11007.559697612005;11044.006039456166;9994;11206.215564910379;11402.486888090221;11410.486528106088;11726.765464690488;7862.646180921758;7990.520568765893;8064.516129032277;8082.7171049013;8112.43212975086;9309.255259579082;10688.038076573146;11122.888771112186;11232.66302010941;11492.367919764492 02:47:34 INFO - PROCESS | 1938 | |3;mootools.html;1570.4267942201097;1580.1151355241063;1806.4309742431133;1915.9616807663856;1967.2622766462578;7977.162397948219;8596.95701521493;8792.912070879289;9010.414323069004;9021.548922553871;8632.136786321365;8715.564221788902;8724.0403555609;8777.517236551987;8802.207801297882;2309.1571576374586;2454.1410506322823;2463.187148241085;2466.025919761693;2492.1651246832334;2088.4465616611533;2423.3093568332943;2445.1808644104244;2460.778529932306;2462.753724627541;2469.604863221885;2476.727559968402;2485.7628134678844;2501.2871331670476;2502.699676038878;2051.5999380120884;2470.555300045991;2481.4292712676097;2485.8508489490596;2502.27434044127;2404.483036147219;2429.769171928664;2477.578811602032;2478.764517370847;2480.8139389545854;7472.51428657141;7512.737054203077;8379.743038544206;8798.692045778367;8999.910000899972;5387.4612538746205;5503.679117011929;5555.027870122713;5557.916631250535;5577.247071645316;3573.535440392758;3640.8543658253902;3715.442683597455;3719.014461167793;3732.5520937487404;1017.2574020964663;1035.9689209323658;1043.8434234864756;1045.7699306152633;1046.1996572621874;11871;11946.701332466671;12175.330356830282;12238.265704057696;12292.631221063382;4800.231962885921;4835.6131509479155;4889.535494128052;4914.066327397783;4954.355933728664;10005.19958403326;10071.244656650633;10218.029287217703;10224.591016359262;10263.12763415118;5328.8401347959625;5374.973125134349;5460.781568737286;5520.171974203901;5560.221568980346;4405.052913623586;4525.366448697119;4698.660881648713;4880.389951256093;4980.601551875841;3349.39710852049;4078.1028173801715;4170;4183.5398106208295;4190.455240818735;1342.912710673803;1359.5309618181711;1365.9248741319125;1372.2246930484243;1374.6013656039836;753.3784627682074;874.9650013999496;899.1188635137471;918.9615734220288;938.7889622387181;6000.279966404059;6009.278886533644;6014.218151640259;6035.185249991283;6221.875562488815;802.6267785479663;856.4604299291407;983.1151963233148;1003.9498025098862;1039.8076355874036;3909.6090390960676;3936.4488971544006;3960.1287716702855;3967.9801600992387;4003.459532963071;9751.317407781387;10034.949825250827;10058.497075146213;10065.496725163712;10097.747556311004;9761.219102471785;9874.160696340892;9886.851697224547;9961.651342202988;9990.80018399628;8323.625436855356;8379.832403351898;8472.068072512018;8519.403641745019;8613.664881943309;4843.854684359475;5511.173324001432;5716.256886604715;5813.6221145625395;6014.6391216527145;9322.580483878377;9499.952500237454;9554.713358599252;9671.419714817133;9730.929597744102;9858.605655773976;9867.40795552269;9868.457234851865;9886.30795844284;10004.499775011365;6269.247690277196;6974.58152510851;7214.494985351185;7465.253474652492;7527.510711803715;8301.169883011651;8336.958315208385;8342.416030877781;8378.958105209678;8398.496090234605;7313.378362839218;7424.1462231844025;7433.182349941394;7516.285952834472;7539;7278.745243916437;7304.086989126359;7356.264373562601;7382;7449.5902725350625;7042.190148132825;7068.222495525385;7084.752033678797;7091.43268538526;7100.928990710234;4827.227643577011;4859.95140048595;4874.171390863633;4896.510348965217;4932.482089380564;4878.0975519529;4897.338859253955;4914.8279810207905;4933.876653083702;5130.256112863689 02:47:34 INFO - PROCESS | 1938 | |4;prototype.html;304.8110171693549;314.8535930792182;318.2552826386257;321.56588605382734;322.4196446396486;5280.9207861882005;6793.592384456923;6848.9657551712235;6882.6558672066385;6883.862322753548;5365.812196573121;6766.052752614625;6777.59334439934;6805.591664500121;6807;4948.084604348189;5023.924641130386;5030.7987680492915;5076.949230507703;5082.2376643503585;5080.974595127019;5089.949100508985;5094.847154585368;5096.719680417576;5121.591562320355;5001.099802035632;5011.899762004758;5023.120953833083;5024.497550244982;5025.396952365703;4493.932591011137;4559.954400455986;4689.437267527918;4855.344528488679;4997.800087996494;4076.5515793262716;4076.7553946763287;4088.7955602220068;4091.8772436826944;4102.671786257092;6062.605600711861;6479.708413121421;6568.6058836469965;6587.440067594261;6600.570962887397;4638.628909687217;5228.764705588258;5729.799457018984;5734.598578099534;5758.539316854642;5905.966455870249;5912.556558258148;7225.783226503213;7265.709371625128;7285.5628662279905;4423.845165419227;4576.336431217454;4577.450705915278;4582.1064892346;4602.9643330250765;6358.618482890995;6541.084248205255;7576.166621671612;7697.268759467897;7722.884156737653;4664.766761661971;4666.253399456071;4668.8132474701315;4674.69614475058;4677.555632214934;4740.5733483986605;4744.122337367598;4747.905041899143;4758.643101767312;4758.78585463655;5394;5743.885122297531;5765.682887441231;5776.249087618583;5792.681402522818;7030.437564994788;7334.413246940232;7335.303146201102;7351.44864135192;7376.704931802775;1625.1305551529874;1628.1370873437097;1643.2687454082818;1655.8923669961414;1657.494464188434;960.2222200018008;1016.7305663998899;1022.1362948308667;1023.0894503891542;1025.7589466475372;6188;6297.52768542352;6715.1941766988275;6861.794146175623;6877.4841886858685;6300.716467758962;7698.307152356315;7749.418793590503;7750.612469376621;7771.417143714244;6840.726370945206;6840.897386539206;6848.315168483112;6861.142357205349;6886.380225779603;3909.58949310324;4035.8385664573675;4090.263752524574;4091.713580049368;4095.508538975342;8101.6354264058255;8124.83750325002;8131.83736325282;8132.3494120471405;8155.592220388957;7935.2858242757275;7991.24083212094;7996.56018918965;8023.997000374953;8027.638756255982;8093.554854482943;8106.229908158715;8112.067112282163;8118.675252989932;8133.430659853753;6591.835204119935;7344.742933997284;7605.6577454015805;7632.465727399029;7733.419993500623;7478.813029674302;7513.511621744569;7558.433117516099;7572.39420846342;7593.734219302298;7991.72028978983;8075.192480751878;8090.919090809017;8111.553864537507;8124.512529248264;4626.629869610356;4681.344611754425;4684.0866031123605;4697.906041879211;4704.741239231738;6046.848828779316;7406.000189974285;7435.144958329862;7682.692692292246;7698.307152356315;7368.226336234618;7384.040074790243;7401.259874012771;7403.037605111516;7424.851502970018;7337.449691273283;7341.045664063638;7360.300771426706;7363.300486453778;7395.445341599294;6971.337722916315;7205.279472052753;7215.232268094351;7216.3505284524645;7216.89174662391;7305.086864141982;7373;7382.2617738225745;7408.111026676834;7419;6870.62211578348;7097.858042839218;7155.642217888981;7209.134903811576;7247.094113235846 02:47:34 INFO - PROCESS | 1938 | |5;yui.html;559.8460423383569;650.2911826109541;718.2171433137881;724.2467833453205;751.8834580639996;15062.322195501201;15212;15269.00751451155;15412.537623871287;15523.767143492843;11736.53053877846;13828.585142445743;14038.157710537374;14250.857491425082;14286.357113929873;746.3618606091795;886.902440731519;895.9865602015959;908.309684639672;908.7728067983004;879.9780005499882;926.4719110107242;931.8835145606799;939.7885475767931;941.1059493481187;818.7380038387719;829.365535365443;959.5490119643756;985.4629227071264;987.0820137272334;949.0888746803078;970.2286682087757;973;978.6819283732815;979.6375341123794;706.2725392845377;709.7870638808389;724.6847621284758;730.3901242462497;735.7608777147449;14915.925420372938;15043.097414155185;15153.696926061417;15159.545213643609;15202.771958420633;12861.164024338388;12921.418536165895;12952.87047129526;13092.803607945889;13223.73552528944;9882.160016398615;10037.799244015152;10277.79444411115;10311.948440257825;10342.224333175041;3472.7048200902955;3493.4934434506854;3528.2237907660274;3567.839447224881;3578.570571531433;80927.97680057987;81484;82319;82426.9999999994;82712.58643706742;9362;9596.90403095974;9622.855657165148;9789.118979291756;10048.397096174107;22349.88250587464;22670.65994010091;22957.885210574175;22965.655515167287;22969.77030229676;7354.448416368794;7601.619919004028;7606.9619651901385;7615.276548727862;7733.613319334123;3397.8301084945656;4023;4083.1017176221185;4085.9795701021303;4091.488563929509;3372.5952885653387;3415.0534195508817;3419.2477654916374;3430.1596108952967;3432.056184549219;663.5952069237761;675.7432175773175;675.7634827810227;678.7183318922591;680.1498127340824;492.75116066386244;536.8630999095284;580.4659713063907;612.1116356041662;613.0803794308537;7891.802704932308;7978.800529986681;8119.512829230265;8263.297619702273;8340.68887544507;479.3959610890234;570.6804189653807;596.5406636889571;610.5237914426755;629.6159342800888;3749.1939233064477;3875.5736868944396;3883.8446462141765;3888.2806680764147;3924.6075392460525;84762.15237847666;92702.53648731713;93456;94480.52759736158;94651;78837.42325153465;79146;79576.60211698904;80957.59521202356;80971;69713;70426.64786676034;71616.64191679009;72432.63783681048;72601.6369918147;8126.146754590683;8360.122187170502;8367.28878045373;8542.44474109181;8624.439411438236;37128;37733.24533509259;47958.28062578994;48962;50833;63366.68316658387;63441;63859;64163;67373.32626673866;10543.20925930543;10626.309289896131;11282.605108821152;11481.36852473122;11958.820617690568;10116.291859570054;11772.823407649063;11959.222650527689;12043.638690839289;12446.190997585129;11479.713007174887;11480.138989575982;11487.94256028748;11568;11821.940890295493;11043.503042362952;11077.169212308949;11199.328040317607;11646.70883227926;12224.205426647239;9368.859467108136;11328.207025508136;11428.657140285804;11435.428228588704;11464.4267786612;7880.960595196987;8262.339012879076;9634.325597208128;9701.417914924845;10026.248031397528;9711.465869377253;9716.271279654196;9797.804043918939;9798;9835.409875407498 02:47:34 INFO - PROCESS | 1938 | __end_tp_report 02:47:34 INFO - PROCESS | 1938 | __start_cc_report 02:47:34 INFO - PROCESS | 1938 | _x_x_mozilla_cycle_collect,414 02:47:34 INFO - PROCESS | 1938 | __end_cc_report 02:47:34 INFO - PROCESS | 1938 | __startTimestamp1462873654920__endTimestamp 02:47:34 INFO - PROCESS | 1938 | 02:47:34 INFO - PROCESS | 1938 | ------- Summary: start ------- 02:47:34 INFO - PROCESS | 1938 | Number of tests: 6 02:47:34 INFO - PROCESS | 1938 | 02:47:34 INFO - PROCESS | 1938 | [#0] dojo.html Cycles:165 Average:5822.65 Median:6058.42 stddev:654.22 (10.8%) stddev-sans-first:628.65 02:47:34 INFO - PROCESS | 1938 | Values: 3426.7 3500.9 3517.0 3547.2 3556.0 5636.4 5841.3 5859.9 5881.4 5900.8 5784.2 5833.8 5850.1 5854.8 5864.7 5316.2 5323.7 5384.4 5395.6 5401.3 5254.7 5380.8 5389.2 5408.7 5410.1 5316.8 5318.5 5341.4 5341.4 5360.0 5299.1 5324.9 5331.5 5334.0 5362.6 4911.6 5186.6 5389.2 5428.5 5451.6 5341.4 5697.0 5843.4 5891.0 5925.9 6098.9 6189.4 6285.7 6286.4 6309.3 5202.6 5780.5 5844.2 6314.2 6324.8 5387.7 5911.4 5969.1 5973.1 5981.0 6258.9 6276.9 6327.6 6399.8 6431.6 6241.5 6249.5 6268.3 6282.2 6291.8 6313.6 6313.8 6328.1 6333.7 6342.3 6133.3 6187.3 6271.0 6274.9 6303.1 6255.4 6280.9 6307.3 6312.8 6344.7 4329.8 5011.1 5012.7 5021.9 5027.1 4369.2 4417.2 4456.2 4460.5 4535.7 4881.0 5848.7 5867.5 5883.5 5911.4 6216.1 6263.7 6298.1 6302.0 6316.8 4858.0 5713.9 5760.6 5862.6 5989.9 5451.6 5474.4 5487.0 5489.0 5500.1 6511.7 6523.0 6527.5 6536.4 6536.6 6184.6 6261.4 6315.2 6330.2 6350.7 6484.9 6529.2 6535.8 6563.6 6577.7 6049.7 6277.3 6297.2 6304.9 6312.1 6214.8 6269.5 6327.2 6330.0 6358.2 5857.5 6195.8 6232.9 6242.2 6260.2 6080.5 6145.2 6213.6 6220.6 6223.4 6040.2 6058.4 6114.1 6176.3 6184.7 6227.5 6265.7 6271.6 6304.0 6308.0 5972.8 6069.9 6111.0 6142.7 6187.4 02:47:34 INFO - PROCESS | 1938 | 02:47:34 INFO - PROCESS | 1938 | [#1] ext.html Cycles:180 Average:66112.68 Median:38815.21 stddev:146940.64 (378.6%) stddev-sans-first:147270.22 02:47:34 INFO - PROCESS | 1938 | Values: 479.9 528.9 553.9 587.0 626.5 38871.2 38880.8 38889.4 39815.2 39863.4 37117.7 37164.0 40484.0 41000.8 41007.6 9393.3 9591.7 9614.9 9656.4 9669.8 9495.8 9605.5 9616.8 9687.2 9718.2 9622.2 9652.4 9711.9 9712.0 9765.9 9580.5 9615.0 9652.5 9712.2 9755.6 9573.3 9743.7 9755.0 9756.5 9780.9 32532.7 39937.6 40111.8 40341.2 40483.0 2718.9 3049.0 3156.4 3165.1 3197.8 28739.4 28788.4 28826.1 29790.9 29892.6 2603.2 2759.3 2819.4 2879.5 2889.7 292889.0 356232.0 357758.2 358306.0 358328.0 36212.8 36806.4 60943.7 61199.0 61506.7 30383.8 35699.3 35780.6 35900.9 35975.8 15338.9 15668.4 15772.1 15918.3 15922.8 11076.0 13012.2 13296.2 13326.7 13341.1 6987.3 8021.5 8159.9 8265.8 8341.6 2794.0 3395.6 3453.1 3478.7 3512.5 949.9 962.9 1063.9 1113.0 1142.2 27099.9 30641.4 32463.4 33001.5 33360.8 1040.9 1137.9 1148.4 1166.3 1191.2 11774.6 15074.8 15157.5 15232.7 15264.2 625726.0 637290.0 645914.0 646895.0 650829.0 58646.4 60418.1 60520.0 60557.0 60598.7 1490.0 1558.1 1572.0 1574.1 1575.0 28776.6 29524.4 29669.4 29881.9 30165.0 53022.7 53703.7 53955.0 54063.5 54094.7 596883.0 605754.0 612747.0 612772.0 613221.0 42781.5 43013.6 44148.2 44258.8 44807.0 47079.3 47181.8 48189.0 48371.3 48919.8 35394.9 38846.8 39157.2 39659.4 39857.0 31820.0 38092.0 38484.2 38783.6 39444.6 38124.6 38626.4 38669.8 39091.8 39157.4 33448.5 33929.0 34295.8 34328.5 34522.3 21850.7 25745.2 25831.9 25969.7 26020.2 02:47:34 INFO - PROCESS | 1938 | 02:47:34 INFO - PROCESS | 1938 | [#2] jquery.html Cycles:180 Average:31139.08 Median:2339.15 stddev:86280.65 (3688.6%) stddev-sans-first:86491.78 02:47:34 INFO - PROCESS | 1938 | Values: 379.3 383.3 383.6 387.7 396.5 19598.0 20708.8 20855.8 21024.3 21101.7 20832.7 21134.6 21623.6 21628.8 21976.9 4386.6 5858.2 6099.7 6123.3 6131.3 3178.6 5613.0 5691.6 5701.1 5812.1 4834.9 5766.8 5806.9 5812.3 5825.5 5592.2 5776.0 6349.8 6436.0 6466.4 5635.9 5649.5 5667.6 5679.2 5687.3 19979.0 22814.1 24025.3 24130.9 24259.4 1759.6 2131.8 2164.4 2230.6 2243.5 9194.0 9240.9 9541.3 9683.9 12483.9 1776.2 1780.9 1787.9 1788.6 1801.3 107893.0 111056.8 118914.0 119255.0 119261.4 10782.2 10882.1 11065.9 11401.7 11507.0 12908.5 13495.0 13561.0 13614.3 13691.2 8610.2 8652.7 8666.1 8791.2 8851.0 7872.7 9124.5 9132.4 9138.3 9155.2 4281.8 4303.1 4367.7 4386.2 4452.9 1750.5 1812.1 1818.5 1856.9 1885.2 820.8 944.1 959.3 964.0 985.0 19354.5 20238.2 20276.7 20359.8 20528.5 339.5 969.2 984.3 991.4 1030.2 5814.7 5945.8 5985.8 5989.6 5996.0 265598.0 267014.0 617482.8 620789.0 621335.0 125943.0 129770.0 130370.3 130601.0 132282.0 430.7 442.0 444.0 444.1 445.6 8780.8 9769.9 10003.8 10646.6 10726.9 51916.0 53382.7 53652.0 53981.2 54926.5 74355.0 76356.1 77221.5 78248.6 80531.8 7323.4 7460.5 7979.7 8063.2 8109.4 12557.1 12589.7 12654.6 12733.6 12932.2 10800.8 10981.3 11335.3 11368.4 11415.4 8757.6 10847.2 10995.0 11007.6 11044.0 9994.0 11206.2 11402.5 11410.5 11726.8 7862.6 7990.5 8064.5 8082.7 8112.4 9309.3 10688.0 11122.9 11232.7 11492.4 02:47:34 INFO - PROCESS | 1938 | 02:47:34 INFO - PROCESS | 1938 | [#3] mootools.html Cycles:180 Average:5688.97 Median:4967.48 stddev:3118.38 (62.8%) stddev-sans-first:3111.77 02:47:34 INFO - PROCESS | 1938 | Values: 1570.4 1580.1 1806.4 1916.0 1967.3 7977.2 8597.0 8792.9 9010.4 9021.5 8632.1 8715.6 8724.0 8777.5 8802.2 2309.2 2454.1 2463.2 2466.0 2492.2 2088.4 2423.3 2445.2 2460.8 2462.8 2469.6 2476.7 2485.8 2501.3 2502.7 2051.6 2470.6 2481.4 2485.9 2502.3 2404.5 2429.8 2477.6 2478.8 2480.8 7472.5 7512.7 8379.7 8798.7 8999.9 5387.5 5503.7 5555.0 5557.9 5577.2 3573.5 3640.9 3715.4 3719.0 3732.6 1017.3 1036.0 1043.8 1045.8 1046.2 11871.0 11946.7 12175.3 12238.3 12292.6 4800.2 4835.6 4889.5 4914.1 4954.4 10005.2 10071.2 10218.0 10224.6 10263.1 5328.8 5375.0 5460.8 5520.2 5560.2 4405.1 4525.4 4698.7 4880.4 4980.6 3349.4 4078.1 4170.0 4183.5 4190.5 1342.9 1359.5 1365.9 1372.2 1374.6 753.4 875.0 899.1 919.0 938.8 6000.3 6009.3 6014.2 6035.2 6221.9 802.6 856.5 983.1 1003.9 1039.8 3909.6 3936.4 3960.1 3968.0 4003.5 9751.3 10034.9 10058.5 10065.5 10097.7 9761.2 9874.2 9886.9 9961.7 9990.8 8323.6 8379.8 8472.1 8519.4 8613.7 4843.9 5511.2 5716.3 5813.6 6014.6 9322.6 9500.0 9554.7 9671.4 9730.9 9858.6 9867.4 9868.5 9886.3 10004.5 6269.2 6974.6 7214.5 7465.3 7527.5 8301.2 8337.0 8342.4 8379.0 8398.5 7313.4 7424.1 7433.2 7516.3 7539.0 7278.7 7304.1 7356.3 7382.0 7449.6 7042.2 7068.2 7084.8 7091.4 7100.9 4827.2 4860.0 4874.2 4896.5 4932.5 4878.1 4897.3 4914.8 4933.9 5130.3 02:47:34 INFO - PROCESS | 1938 | 02:47:34 INFO - PROCESS | 1938 | [#4] prototype.html Cycles:180 Average:5922.85 Median:6740.62 stddev:1957.93 (29.0%) stddev-sans-first:1917.47 02:47:34 INFO - PROCESS | 1938 | Values: 304.8 314.9 318.3 321.6 322.4 5280.9 6793.6 6849.0 6882.7 6883.9 5365.8 6766.1 6777.6 6805.6 6807.0 4948.1 5023.9 5030.8 5076.9 5082.2 5081.0 5089.9 5094.8 5096.7 5121.6 5001.1 5011.9 5023.1 5024.5 5025.4 4493.9 4560.0 4689.4 4855.3 4997.8 4076.6 4076.8 4088.8 4091.9 4102.7 6062.6 6479.7 6568.6 6587.4 6600.6 4638.6 5228.8 5729.8 5734.6 5758.5 5906.0 5912.6 7225.8 7265.7 7285.6 4423.8 4576.3 4577.5 4582.1 4603.0 6358.6 6541.1 7576.2 7697.3 7722.9 4664.8 4666.3 4668.8 4674.7 4677.6 4740.6 4744.1 4747.9 4758.6 4758.8 5394.0 5743.9 5765.7 5776.2 5792.7 7030.4 7334.4 7335.3 7351.4 7376.7 1625.1 1628.1 1643.3 1655.9 1657.5 960.2 1016.7 1022.1 1023.1 1025.8 6188.0 6297.5 6715.2 6861.8 6877.5 6300.7 7698.3 7749.4 7750.6 7771.4 6840.7 6840.9 6848.3 6861.1 6886.4 3909.6 4035.8 4090.3 4091.7 4095.5 8101.6 8124.8 8131.8 8132.3 8155.6 7935.3 7991.2 7996.6 8024.0 8027.6 8093.6 8106.2 8112.1 8118.7 8133.4 6591.8 7344.7 7605.7 7632.5 7733.4 7478.8 7513.5 7558.4 7572.4 7593.7 7991.7 8075.2 8090.9 8111.6 8124.5 4626.6 4681.3 4684.1 4697.9 4704.7 6046.8 7406.0 7435.1 7682.7 7698.3 7368.2 7384.0 7401.3 7403.0 7424.9 7337.4 7341.0 7360.3 7363.3 7395.4 6971.3 7205.3 7215.2 7216.4 7216.9 7305.1 7373.0 7382.3 7408.1 7419.0 6870.6 7097.9 7155.6 7209.1 7247.1 02:47:34 INFO - PROCESS | 1938 | 02:47:34 INFO - PROCESS | 1938 | [#5] yui.html Cycles:180 Average:18204.13 Median:31998.15 stddev:25639.91 (80.1%) stddev-sans-first:25677.61 02:47:34 INFO - PROCESS | 1938 | Values: 559.8 650.3 718.2 724.2 751.9 15062.3 15212.0 15269.0 15412.5 15523.8 11736.5 13828.6 14038.2 14250.9 14286.4 746.4 886.9 896.0 908.3 908.8 880.0 926.5 931.9 939.8 941.1 818.7 829.4 959.5 985.5 987.1 949.1 970.2 973.0 978.7 979.6 706.3 709.8 724.7 730.4 735.8 14915.9 15043.1 15153.7 15159.5 15202.8 12861.2 12921.4 12952.9 13092.8 13223.7 9882.2 10037.8 10277.8 10311.9 10342.2 3472.7 3493.5 3528.2 3567.8 3578.6 80928.0 81484.0 82319.0 82427.0 82712.6 9362.0 9596.9 9622.9 9789.1 10048.4 22349.9 22670.7 22957.9 22965.7 22969.8 7354.4 7601.6 7607.0 7615.3 7733.6 3397.8 4023.0 4083.1 4086.0 4091.5 3372.6 3415.1 3419.2 3430.2 3432.1 663.6 675.7 675.8 678.7 680.1 492.8 536.9 580.5 612.1 613.1 7891.8 7978.8 8119.5 8263.3 8340.7 479.4 570.7 596.5 610.5 629.6 3749.2 3875.6 3883.8 3888.3 3924.6 84762.2 92702.5 93456.0 94480.5 94651.0 78837.4 79146.0 79576.6 80957.6 80971.0 69713.0 70426.6 71616.6 72432.6 72601.6 8126.1 8360.1 8367.3 8542.4 8624.4 37128.0 37733.2 47958.3 48962.0 50833.0 63366.7 63441.0 63859.0 64163.0 67373.3 10543.2 10626.3 11282.6 11481.4 11958.8 10116.3 11772.8 11959.2 12043.6 12446.2 11479.7 11480.1 11487.9 11568.0 11821.9 11043.5 11077.2 11199.3 11646.7 12224.2 9368.9 11328.2 11428.7 11435.4 11464.4 7881.0 8262.3 9634.3 9701.4 10026.2 9711.5 9716.3 9797.8 9798.0 9835.4 02:47:34 INFO - PROCESS | 1938 | -------- Summary: end -------- 02:47:34 INFO - PROCESS | 1938 | 02:47:35 INFO - TEST-INFO | 1938: exit 0 02:47:35 INFO - TEST-OK | dromaeo_css | took 1111339ms 02:47:35 INFO - TEST-START | kraken 02:47:35 INFO - operating with platform_type : mac_ 02:47:35 INFO - Initialising browser for kraken test... 02:47:35 INFO - TEST-INFO | started process 1947 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/tmpkYNhY9/profile http://localhost:50922/getInfo.html) 02:47:38 INFO - PROCESS | 1947 | __metrics Screen width/height:1600/1200 02:47:38 INFO - PROCESS | 1947 | colorDepth:24 02:47:38 INFO - PROCESS | 1947 | Browser inner width/height: 1024/689 02:47:38 INFO - PROCESS | 1947 | __metrics 02:47:38 ERROR - PROCESS | 1947 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 02:47:39 INFO - TEST-INFO | 1947: exit 0 02:47:39 INFO - Browser initialized. 02:47:39 INFO - Running cycle 1/1 for kraken test... 02:47:39 INFO - TEST-INFO | started process 1951 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/T/tmpkYNhY9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:47:51 INFO - PROCESS | 1951 | RSS: Main: 220987392 02:47:51 INFO - PROCESS | 1951 | 02:47:54 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:50922/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50922/tests/kraken/driver.html?testName=audio-beat-detection) 02:47:54 INFO - PROCESS | 1951 | RSS: Main: 232947712 02:47:54 INFO - PROCESS | 1951 | 02:47:57 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:50922/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50922/tests/kraken/driver.html?testName=audio-dft) 02:47:58 INFO - PROCESS | 1951 | RSS: Main: 241790976 02:47:58 INFO - PROCESS | 1951 | 02:48:01 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:50922/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50922/tests/kraken/driver.html?testName=audio-fft) 02:48:02 INFO - PROCESS | 1951 | RSS: Main: 246038528 02:48:02 INFO - PROCESS | 1951 | 02:48:04 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:50922/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50922/tests/kraken/driver.html?testName=audio-oscillator) 02:48:04 INFO - PROCESS | 1951 | RSS: Main: 245706752 02:48:04 INFO - PROCESS | 1951 | 02:48:07 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:50922/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50922/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:48:07 INFO - PROCESS | 1951 | RSS: Main: 247709696 02:48:07 INFO - PROCESS | 1951 | 02:48:10 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:50922/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50922/tests/kraken/driver.html?testName=imaging-darkroom) 02:48:11 INFO - PROCESS | 1951 | RSS: Main: 247619584 02:48:11 INFO - PROCESS | 1951 | 02:48:14 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:50922/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50922/tests/kraken/driver.html?testName=imaging-desaturate) 02:48:14 INFO - PROCESS | 1951 | RSS: Main: 247676928 02:48:14 INFO - PROCESS | 1951 | 02:48:17 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:50922/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50922/tests/kraken/driver.html?testName=json-parse-financial) 02:48:18 INFO - PROCESS | 1951 | RSS: Main: 247795712 02:48:18 INFO - PROCESS | 1951 | 02:48:19 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:50922/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50922/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:48:20 INFO - PROCESS | 1951 | RSS: Main: 247443456 02:48:20 INFO - PROCESS | 1951 | 02:48:22 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:50922/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50922/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:48:22 INFO - PROCESS | 1951 | RSS: Main: 247320576 02:48:22 INFO - PROCESS | 1951 | 02:48:24 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:50922/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50922/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:48:25 INFO - PROCESS | 1951 | RSS: Main: 247570432 02:48:25 INFO - PROCESS | 1951 | 02:48:28 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:50922/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50922/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:48:28 INFO - PROCESS | 1951 | RSS: Main: 247799808 02:48:28 INFO - PROCESS | 1951 | 02:48:32 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:50922/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50922/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:48:32 INFO - PROCESS | 1951 | RSS: Main: 247705600 02:48:32 INFO - PROCESS | 1951 | 02:48:34 INFO - PROCESS | 1951 | Cycle 1(1): loaded http://localhost:50922/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50922/tests/kraken/driver.html?testName=ai-astar) 02:48:34 INFO - PROCESS | 1951 | RSS: Main: 249712640 02:48:34 INFO - PROCESS | 1951 | 02:48:34 INFO - PROCESS | 1951 | __start_tp_report 02:48:34 INFO - PROCESS | 1951 | _x_x_mozilla_page_load 02:48:34 INFO - PROCESS | 1951 | _x_x_mozilla_page_load_details 02:48:34 INFO - PROCESS | 1951 | |i|pagename|runs| 02:48:34 INFO - PROCESS | 1951 | |0;ai-astar;114;119;108;119;122;117;108;115;116;114 02:48:34 INFO - PROCESS | 1951 | |1;audio-beat-detection;150;122;152;189;108;180;175;154;161;121 02:48:34 INFO - PROCESS | 1951 | |2;audio-dft;196;194;198;226;228;198;194;292;247;198 02:48:34 INFO - PROCESS | 1951 | |3;audio-fft;66;81;74;142;55;55;56;66;118;59 02:48:34 INFO - PROCESS | 1951 | |4;audio-oscillator;80;82;128;76;80;86;139;121;119;150 02:48:34 INFO - PROCESS | 1951 | |5;imaging-gaussian-blur;89;86;88;86;90;86;88;86;89;85 02:48:34 INFO - PROCESS | 1951 | |6;imaging-darkroom;139;152;94;95;94;97;94;107;99;154 02:48:34 INFO - PROCESS | 1951 | |7;imaging-desaturate;92;96;90;92;94;89;91;93;91;91 02:48:34 INFO - PROCESS | 1951 | |8;json-parse-financial;61;66;65;65;67;67;66;81;65;61 02:48:34 INFO - PROCESS | 1951 | |9;json-stringify-tinderbox;71;66;70;73;81;72;69;72;68;72 02:48:34 INFO - PROCESS | 1951 | |10;stanford-crypto-aes;81;83;80;80;81;80;82;83;84;87 02:48:34 INFO - PROCESS | 1951 | |11;stanford-crypto-ccm;128;126;132;128;131;130;129;126;150;144 02:48:34 INFO - PROCESS | 1951 | |12;stanford-crypto-pbkdf2;225;220;164;172;206;170;173;218;200;222 02:48:34 INFO - PROCESS | 1951 | |13;stanford-crypto-sha256-iterative;66;69;68;67;85;67;89;66;55;58 02:48:34 INFO - PROCESS | 1951 | __end_tp_report 02:48:34 INFO - PROCESS | 1951 | __start_cc_report 02:48:34 INFO - PROCESS | 1951 | _x_x_mozilla_cycle_collect,1083 02:48:34 INFO - PROCESS | 1951 | __end_cc_report 02:48:34 INFO - PROCESS | 1951 | __startTimestamp1462873714662__endTimestamp 02:48:34 INFO - PROCESS | 1951 | 02:48:34 INFO - PROCESS | 1951 | ------- Summary: start ------- 02:48:34 INFO - PROCESS | 1951 | Number of tests: 14 02:48:34 INFO - PROCESS | 1951 | 02:48:34 INFO - PROCESS | 1951 | [#0] ai-astar Cycles:10 Average:115.20 Median:116.50 stddev:4.54 (3.9%) stddev-sans-first:4.80 02:48:34 INFO - PROCESS | 1951 | Values: 114.0 119.0 108.0 119.0 122.0 117.0 108.0 115.0 116.0 114.0 02:48:34 INFO - PROCESS | 1951 | 02:48:34 INFO - PROCESS | 1951 | [#1] audio-beat-detection Cycles:10 Average:151.20 Median:157.50 stddev:27.00 (17.1%) stddev-sans-first:28.64 02:48:34 INFO - PROCESS | 1951 | Values: 150.0 122.0 152.0 189.0 108.0 180.0 175.0 154.0 161.0 121.0 02:48:34 INFO - PROCESS | 1951 | 02:48:34 INFO - PROCESS | 1951 | [#2] audio-dft Cycles:10 Average:217.10 Median:212.00 stddev:32.16 (15.2%) stddev-sans-first:33.19 02:48:34 INFO - PROCESS | 1951 | Values: 196.0 194.0 198.0 226.0 228.0 198.0 194.0 292.0 247.0 198.0 02:48:34 INFO - PROCESS | 1951 | 02:48:34 INFO - PROCESS | 1951 | [#3] audio-fft Cycles:10 Average:77.20 Median:62.50 stddev:29.64 (47.4%) stddev-sans-first:31.16 02:48:34 INFO - PROCESS | 1951 | Values: 66.0 81.0 74.0 142.0 55.0 55.0 56.0 66.0 118.0 59.0 02:48:34 INFO - PROCESS | 1951 | 02:48:34 INFO - PROCESS | 1951 | [#4] audio-oscillator Cycles:10 Average:106.10 Median:78.00 stddev:28.15 (36.1%) stddev-sans-first:28.23 02:48:34 INFO - PROCESS | 1951 | Values: 80.0 82.0 128.0 76.0 80.0 86.0 139.0 121.0 119.0 150.0 02:48:34 INFO - PROCESS | 1951 | 02:48:34 INFO - PROCESS | 1951 | [#5] imaging-gaussian-blur Cycles:10 Average:87.30 Median:88.00 stddev:1.70 (1.9%) stddev-sans-first:1.69 02:48:34 INFO - PROCESS | 1951 | Values: 89.0 86.0 88.0 86.0 90.0 86.0 88.0 86.0 89.0 85.0 02:48:34 INFO - PROCESS | 1951 | 02:48:34 INFO - PROCESS | 1951 | [#6] imaging-darkroom Cycles:10 Average:112.50 Median:94.00 stddev:25.32 (26.9%) stddev-sans-first:24.98 02:48:34 INFO - PROCESS | 1951 | Values: 139.0 152.0 94.0 95.0 94.0 97.0 94.0 107.0 99.0 154.0 02:48:34 INFO - PROCESS | 1951 | 02:48:34 INFO - PROCESS | 1951 | [#7] imaging-desaturate Cycles:10 Average:91.90 Median:92.00 stddev:2.02 (2.2%) stddev-sans-first:2.15 02:48:34 INFO - PROCESS | 1951 | Values: 92.0 96.0 90.0 92.0 94.0 89.0 91.0 93.0 91.0 91.0 02:48:34 INFO - PROCESS | 1951 | 02:48:34 INFO - PROCESS | 1951 | [#8] json-parse-financial Cycles:10 Average:66.40 Median:66.00 stddev:5.56 (8.4%) stddev-sans-first:5.55 02:48:34 INFO - PROCESS | 1951 | Values: 61.0 66.0 65.0 65.0 67.0 67.0 66.0 81.0 65.0 61.0 02:48:34 INFO - PROCESS | 1951 | 02:48:34 INFO - PROCESS | 1951 | [#9] json-stringify-tinderbox Cycles:10 Average:71.40 Median:72.00 stddev:4.01 (5.6%) stddev-sans-first:4.25 02:48:34 INFO - PROCESS | 1951 | Values: 71.0 66.0 70.0 73.0 81.0 72.0 69.0 72.0 68.0 72.0 02:48:34 INFO - PROCESS | 1951 | 02:48:34 INFO - PROCESS | 1951 | [#10] stanford-crypto-aes Cycles:10 Average:82.10 Median:82.50 stddev:2.23 (2.7%) stddev-sans-first:2.33 02:48:34 INFO - PROCESS | 1951 | Values: 81.0 83.0 80.0 80.0 81.0 80.0 82.0 83.0 84.0 87.0 02:48:34 INFO - PROCESS | 1951 | 02:48:34 INFO - PROCESS | 1951 | [#11] stanford-crypto-ccm Cycles:10 Average:132.40 Median:130.50 stddev:8.06 (6.2%) stddev-sans-first:8.39 02:48:34 INFO - PROCESS | 1951 | Values: 128.0 126.0 132.0 128.0 131.0 130.0 129.0 126.0 150.0 144.0 02:48:34 INFO - PROCESS | 1951 | 02:48:34 INFO - PROCESS | 1951 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:197.00 Median:212.00 stddev:24.69 (11.6%) stddev-sans-first:24.02 02:48:34 INFO - PROCESS | 1951 | Values: 225.0 220.0 164.0 172.0 206.0 170.0 173.0 218.0 200.0 222.0 02:48:34 INFO - PROCESS | 1951 | 02:48:34 INFO - PROCESS | 1951 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.00 Median:67.50 stddev:10.54 (15.6%) stddev-sans-first:11.12 02:48:34 INFO - PROCESS | 1951 | Values: 66.0 69.0 68.0 67.0 85.0 67.0 89.0 66.0 55.0 58.0 02:48:34 INFO - PROCESS | 1951 | -------- Summary: end -------- 02:48:34 INFO - PROCESS | 1951 | 02:48:35 INFO - TEST-INFO | 1951: exit 0 02:48:35 INFO - TEST-OK | kraken | took 60009ms 02:48:35 INFO - SUITE-END | took 1171s 02:48:35 INFO - Completed test suite (00:19:31) 02:48:35 INFO - javascript benchmark 02:48:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3426.657334266573, 3500.8949731508064, 3516.9800757780245, 3547.1841476460413, 3555.964440355596, 5636.436356364372, 5841.299044114701, 5859.941400585998, 5881.411858814122, 5900.763969441217, 5784.1902133701315, 5833.79581714639, 5850.063989761639, 5854.824355269338, 5864.677442740647, 5316.229146773716, 5323.680579165243, 5384.3807962084275, 5395.649282796625, 5401.297831281928, 5254.737263136847, 5380.757865896044, 5389.245505629215, 5408.702521361323, 5410.053240682884, 5316.840494785143, 5318.547923426513, 5341.385740639818, 5341.385740639837, 5359.9732001339935, 5299.072662284123, 5324.946750532484, 5331.5201631853315, 5334.03987282289, 5362.624616276862, 4911.557959783637, 5186.5850731941355, 5389.245505629215, 5428.484293992104, 5451.563874889999, 5341.43914888935, 5696.971515142398, 5843.386444423317, 5890.97054514729, 5925.851853703692, 6098.8780224395705, 6189.412005859436, 6285.685715714196, 6286.402791734778, 6309.274433440167, 5202.557782588485, 5780.479756821906, 5844.1818145459665, 6314.179156709644, 6324.778632747878, 5387.676739395571, 5911.438413350725, 5969.104634304846, 5973.074173503114, 5981.043033114681, 6258.906116408258, 6276.905846412307, 6327.620342779448, 6399.776007839796, 6431.5819471734185, 6241.469475094668, 6249.531285153632, 6268.27914789805, 6282.246130464282, 6291.811245662638, 6313.55805093639, 6313.77901773445, 6328.11406403104, 6333.714982825784, 6342.270638876496, 6133.29467111279, 6187.319394866561, 6271.0, 6274.905876411857, 6303.054541818764, 6255.374462553708, 6280.90084235257, 6307.306196318493, 6312.8421789454715, 6344.714487848058, 4329.805158767863, 5011.072951503984, 5012.749362531858, 5021.874453138701, 5027.069992051483, 4369.213541562485, 4417.249067658506, 4456.197884380843, 4460.464744230713, 4535.727856328631, 4880.975595122072, 5848.678322692293, 5867.501262392659, 5883.499902508334, 5911.379305172981, 6216.129741836146, 6263.655498947511, 6298.0552917062805, 6301.96849015752, 6316.842078948063, 4857.975710121426, 5713.885722285531, 5760.596758226968, 5862.618929769551, 5989.940100598938, 5451.591130665216, 5474.370447398521, 5487.0, 5488.984537860509, 5500.119980803052, 6511.706973186123, 6522.988936714817, 6527.477801775752, 6536.444402225864, 6536.575122617015, 6184.628922264678, 6261.4364707174755, 6315.242170939517, 6330.208723909512, 6350.650714210578, 6484.935150648622, 6529.216494020748, 6535.836604084746, 6563.606183628997, 6577.736890524517, 6049.727762250621, 6277.340879207617, 6297.1813664223355, 6304.905426418699, 6312.116303717575, 6214.844628884314, 6269.467095296768, 6327.209098862642, 6329.968350158219, 6358.237011558643, 5857.502112320501, 6195.752169913333, 6232.875342493034, 6242.157308763441, 6260.154879091447, 6080.543959203166, 6145.201123853871, 6213.627182369072, 6220.626762394271, 6223.37766223374, 6040.184575082308, 6058.4244496772735, 6114.145302971568, 6176.289726681489, 6184.721687523982, 6227.501799856092, 6265.655388953652, 6271.592346497463, 6304.0, 6307.96846015767, 5972.820815375546, 6069.908951365821, 6110.953680641429, 6142.662153581596, 6187.412195841388], "value": 5782.141501438973, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [479.92561153021273, 528.912729399649, 553.8532288943428, 586.9815869466474, 626.5457543281121, 38871.22257554847, 38880.805595972015, 38889.41665875014, 39815.2036959261, 39863.40204896929, 37117.70088046912, 37164.0, 40484.0, 41000.794996024975, 41007.56473523427, 9393.342466027363, 9591.712248632552, 9614.855777163348, 9656.372335798185, 9669.758256043584, 9495.762605934839, 9605.519724013806, 9616.759581010461, 9687.225021998223, 9718.173955213815, 9622.230221582256, 9652.420854748703, 9711.902880971205, 9712.028797120302, 9765.90234097657, 9580.520973951345, 9615.0, 9652.469114198717, 9712.22302215821, 9755.560999755027, 9573.28200384974, 9743.65897193602, 9754.975727548646, 9756.512174391322, 9780.853287200698, 32532.6746732532, 39937.600623993676, 40111.7994410029, 40341.193176136316, 40483.0, 2718.9320266993286, 3048.969510304913, 3156.4002839460427, 3165.129589362921, 3197.7921435106878, 28739.42521149586, 28788.424231515462, 28826.135215943556, 29790.851045744632, 29892.55161172584, 2603.2060221632396, 2759.310172456886, 2819.407924335892, 2879.4816932952062, 2889.6965818589165, 292889.0, 356232.0, 357758.2112089449, 358306.0, 358328.0, 36212.818935905154, 36806.44790328147, 60943.6952815242, 61199.0, 61506.692466538276, 30383.848080759453, 35699.286014280086, 35780.64219357773, 35900.92297231087, 35975.820120899225, 15338.926275160855, 15668.373265069498, 15772.05367677943, 15918.283677234553, 15922.76115858263, 11076.0, 13012.154209976321, 13296.202227866359, 13326.666833329244, 13341.066125371131, 6987.336203060701, 8021.5187088774865, 8159.877601835978, 8265.83468330642, 8341.582920853933, 2794.0081271148856, 3395.6434574369337, 3453.1367158210446, 3478.6869181773254, 3512.5433693619666, 949.9335046546813, 962.9133377996014, 1063.9202059845543, 1112.9554817807198, 1142.2289954280827, 27099.86450067737, 30641.38717225643, 32463.350732985207, 33001.50497742536, 33360.83319583387, 1040.8594839696543, 1137.863456385239, 1148.4430051425052, 1166.276908316849, 1191.1780871198846, 11774.608044305409, 15074.820982763675, 15157.469488567847, 15232.695346093016, 15264.160471174193, 625726.0, 637290.0, 645914.0, 646895.0, 650829.0, 58646.41353586495, 60418.09372859323, 60520.0, 60557.0, 60598.69700651557, 1489.977650335246, 1558.057375287957, 1572.0, 1574.1106274954614, 1574.9842501574838, 28776.568351474325, 29524.409511809215, 29669.406611868075, 29881.850590746908, 30165.0, 53022.73488632532, 53703.73148134234, 53955.0, 54063.45936540584, 54094.729526352115, 596883.0, 605754.0, 612747.0, 612772.0, 613221.0, 42781.50264740719, 43013.56986430221, 44148.23407063681, 44258.77870610626, 44807.0, 47079.293810592186, 47181.76409117933, 48189.0, 48371.27443088286, 48919.75540122276, 35394.93815185549, 38846.805765972116, 39157.216855662155, 39659.405108923966, 39857.0, 31820.0, 38092.0, 38484.230315392975, 38783.612163878, 39444.60555394524, 38124.618753813214, 38626.42060369041, 38669.806650967694, 39091.804540977115, 39157.412638809874, 33448.498272525445, 33929.0, 34295.828520857234, 34328.48507272443, 34522.30955380928, 21850.672239916097, 25745.227643170736, 25831.870840645675, 25969.740302596732, 26020.21939341823], "value": 18784.230507551052, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.2869405517627, 383.31004192453594, 383.57614835606654, 387.6821006774445, 396.4647725570482, 19598.0, 20708.792912070876, 20855.791442085574, 21024.264150754727, 21101.68347474789, 20832.687509687366, 21134.577308453823, 21623.56752864942, 21628.810415427186, 21976.89011554944, 4386.649068074546, 5858.209141765849, 6099.695015249242, 6123.326434092269, 6131.294901086388, 3178.634457037436, 5612.971935140318, 5691.601587888849, 5701.116326969326, 5812.1281807728965, 4834.9033019339595, 5766.769329226826, 5806.912896306538, 5812.273465816773, 5825.475707186352, 5592.245046918675, 5775.971120144393, 6349.8412539686415, 6436.0, 6466.353364663543, 5635.8591035224035, 5649.4633009864, 5667.574931880125, 5679.2333035040565, 5687.317521897357, 19979.001049947445, 22814.08743650252, 24025.27924162278, 24130.879345603335, 24259.393515162086, 1759.6304775997055, 2131.765505794361, 2164.426426996847, 2230.553889222162, 2243.4839986802963, 9193.954030229874, 9240.907590924138, 9541.33210675253, 9683.90316096837, 12483.875161248363, 1776.2184638759034, 1780.866435017379, 1787.8647059117425, 1788.5886246163325, 1801.2794882047156, 107893.0, 111056.75879384446, 118914.0, 119255.0, 119261.40369298185, 10782.245242833083, 10882.129429645609, 11065.944670276596, 11401.657950261506, 11506.964373206452, 12908.483660653657, 13495.0, 13560.98292628037, 13614.251216183206, 13691.246981416118, 8610.225079742853, 8652.653893844175, 8666.090060543678, 8791.208791208694, 8850.955745221361, 7872.685092596346, 9124.49815260167, 9132.360734748503, 9138.268938484905, 9155.22180614642, 4281.828726850891, 4303.096349766522, 4367.737935723867, 4386.210482113251, 4452.888677783017, 1750.4661078371219, 1812.0935601518652, 1818.5271829324472, 1856.851451883846, 1885.151681743221, 820.7660816667341, 944.0700909604035, 959.3428501476511, 963.9614415423305, 984.9852252216223, 19354.516137096685, 20238.19047238094, 20276.695849562268, 20359.796402036085, 20528.486787830425, 339.45347989736507, 969.2439896880445, 984.3208186351395, 991.3704797453669, 1030.1912998296302, 5814.680192589448, 5945.821625351246, 5985.760569577168, 5989.55078369124, 5996.0, 265598.0, 267014.0, 617482.8251717426, 620789.0, 621335.0, 125943.0, 129770.0, 130370.34814825865, 130601.0, 132282.0, 430.70281505760926, 441.97348159110555, 443.98890027749565, 444.1095603315332, 445.56557356577076, 8780.78048048804, 9769.902300976899, 10003.799924001625, 10646.574137034431, 10726.946365268435, 51916.0, 53382.73308633432, 53652.0, 53981.19028214501, 54926.45073549214, 74355.0, 76356.12794848491, 77221.45557088715, 78248.60875695584, 80531.79202312087, 7323.414126869944, 7460.477766556289, 7979.72070977513, 8063.153368896182, 8109.351251899716, 12557.121001529806, 12589.685257868627, 12654.620361389174, 12733.55432559856, 12932.224066556037, 10800.783984320427, 10981.341119532854, 11335.263207891461, 11368.431578421212, 11415.37215453158, 8757.649694012169, 10847.186461015614, 10995.0, 11007.559697612005, 11044.006039456166, 9994.0, 11206.215564910379, 11402.486888090221, 11410.486528106088, 11726.765464690488, 7862.646180921758, 7990.520568765893, 8064.516129032277, 8082.7171049013, 8112.43212975086, 9309.255259579082, 10688.038076573146, 11122.888771112186, 11232.66302010941, 11492.367919764492], "value": 8829.367117894919, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1570.4267942201097, 1580.1151355241063, 1806.4309742431133, 1915.9616807663856, 1967.2622766462578, 7977.162397948219, 8596.95701521493, 8792.912070879289, 9010.414323069004, 9021.548922553871, 8632.136786321365, 8715.564221788902, 8724.0403555609, 8777.517236551987, 8802.207801297882, 2309.1571576374586, 2454.1410506322823, 2463.187148241085, 2466.025919761693, 2492.1651246832334, 2088.4465616611533, 2423.3093568332943, 2445.1808644104244, 2460.778529932306, 2462.753724627541, 2469.604863221885, 2476.727559968402, 2485.7628134678844, 2501.2871331670476, 2502.699676038878, 2051.5999380120884, 2470.555300045991, 2481.4292712676097, 2485.8508489490596, 2502.27434044127, 2404.483036147219, 2429.769171928664, 2477.578811602032, 2478.764517370847, 2480.8139389545854, 7472.51428657141, 7512.737054203077, 8379.743038544206, 8798.692045778367, 8999.910000899972, 5387.4612538746205, 5503.679117011929, 5555.027870122713, 5557.916631250535, 5577.247071645316, 3573.535440392758, 3640.8543658253902, 3715.442683597455, 3719.014461167793, 3732.5520937487404, 1017.2574020964663, 1035.9689209323658, 1043.8434234864756, 1045.7699306152633, 1046.1996572621874, 11871.0, 11946.701332466671, 12175.330356830282, 12238.265704057696, 12292.631221063382, 4800.231962885921, 4835.6131509479155, 4889.535494128052, 4914.066327397783, 4954.355933728664, 10005.19958403326, 10071.244656650633, 10218.029287217703, 10224.591016359262, 10263.12763415118, 5328.8401347959625, 5374.973125134349, 5460.781568737286, 5520.171974203901, 5560.221568980346, 4405.052913623586, 4525.366448697119, 4698.660881648713, 4880.389951256093, 4980.601551875841, 3349.39710852049, 4078.1028173801715, 4170.0, 4183.5398106208295, 4190.455240818735, 1342.912710673803, 1359.5309618181711, 1365.9248741319125, 1372.2246930484243, 1374.6013656039836, 753.3784627682074, 874.9650013999496, 899.1188635137471, 918.9615734220288, 938.7889622387181, 6000.279966404059, 6009.278886533644, 6014.218151640259, 6035.185249991283, 6221.875562488815, 802.6267785479663, 856.4604299291407, 983.1151963233148, 1003.9498025098862, 1039.8076355874036, 3909.6090390960676, 3936.4488971544006, 3960.1287716702855, 3967.9801600992387, 4003.459532963071, 9751.317407781387, 10034.949825250827, 10058.497075146213, 10065.496725163712, 10097.747556311004, 9761.219102471785, 9874.160696340892, 9886.851697224547, 9961.651342202988, 9990.80018399628, 8323.625436855356, 8379.832403351898, 8472.068072512018, 8519.403641745019, 8613.664881943309, 4843.854684359475, 5511.173324001432, 5716.256886604715, 5813.6221145625395, 6014.6391216527145, 9322.580483878377, 9499.952500237454, 9554.713358599252, 9671.419714817133, 9730.929597744102, 9858.605655773976, 9867.40795552269, 9868.457234851865, 9886.30795844284, 10004.499775011365, 6269.247690277196, 6974.58152510851, 7214.494985351185, 7465.253474652492, 7527.510711803715, 8301.169883011651, 8336.958315208385, 8342.416030877781, 8378.958105209678, 8398.496090234605, 7313.378362839218, 7424.1462231844025, 7433.182349941394, 7516.285952834472, 7539.0, 7278.745243916437, 7304.086989126359, 7356.264373562601, 7382.0, 7449.5902725350625, 7042.190148132825, 7068.222495525385, 7084.752033678797, 7091.43268538526, 7100.928990710234, 4827.227643577011, 4859.95140048595, 4874.171390863633, 4896.510348965217, 4932.482089380564, 4878.0975519529, 4897.338859253955, 4914.8279810207905, 4933.876653083702, 5130.256112863689], "value": 4612.182378302312, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.8110171693549, 314.8535930792182, 318.2552826386257, 321.56588605382734, 322.4196446396486, 5280.9207861882005, 6793.592384456923, 6848.9657551712235, 6882.6558672066385, 6883.862322753548, 5365.812196573121, 6766.052752614625, 6777.59334439934, 6805.591664500121, 6807.0, 4948.084604348189, 5023.924641130386, 5030.7987680492915, 5076.949230507703, 5082.2376643503585, 5080.974595127019, 5089.949100508985, 5094.847154585368, 5096.719680417576, 5121.591562320355, 5001.099802035632, 5011.899762004758, 5023.120953833083, 5024.497550244982, 5025.396952365703, 4493.932591011137, 4559.954400455986, 4689.437267527918, 4855.344528488679, 4997.800087996494, 4076.5515793262716, 4076.7553946763287, 4088.7955602220068, 4091.8772436826944, 4102.671786257092, 6062.605600711861, 6479.708413121421, 6568.6058836469965, 6587.440067594261, 6600.570962887397, 4638.628909687217, 5228.764705588258, 5729.799457018984, 5734.598578099534, 5758.539316854642, 5905.966455870249, 5912.556558258148, 7225.783226503213, 7265.709371625128, 7285.5628662279905, 4423.845165419227, 4576.336431217454, 4577.450705915278, 4582.1064892346, 4602.9643330250765, 6358.618482890995, 6541.084248205255, 7576.166621671612, 7697.268759467897, 7722.884156737653, 4664.766761661971, 4666.253399456071, 4668.8132474701315, 4674.69614475058, 4677.555632214934, 4740.5733483986605, 4744.122337367598, 4747.905041899143, 4758.643101767312, 4758.78585463655, 5394.0, 5743.885122297531, 5765.682887441231, 5776.249087618583, 5792.681402522818, 7030.437564994788, 7334.413246940232, 7335.303146201102, 7351.44864135192, 7376.704931802775, 1625.1305551529874, 1628.1370873437097, 1643.2687454082818, 1655.8923669961414, 1657.494464188434, 960.2222200018008, 1016.7305663998899, 1022.1362948308667, 1023.0894503891542, 1025.7589466475372, 6188.0, 6297.52768542352, 6715.1941766988275, 6861.794146175623, 6877.4841886858685, 6300.716467758962, 7698.307152356315, 7749.418793590503, 7750.612469376621, 7771.417143714244, 6840.726370945206, 6840.897386539206, 6848.315168483112, 6861.142357205349, 6886.380225779603, 3909.58949310324, 4035.8385664573675, 4090.263752524574, 4091.713580049368, 4095.508538975342, 8101.6354264058255, 8124.83750325002, 8131.83736325282, 8132.3494120471405, 8155.592220388957, 7935.2858242757275, 7991.24083212094, 7996.56018918965, 8023.997000374953, 8027.638756255982, 8093.554854482943, 8106.229908158715, 8112.067112282163, 8118.675252989932, 8133.430659853753, 6591.835204119935, 7344.742933997284, 7605.6577454015805, 7632.465727399029, 7733.419993500623, 7478.813029674302, 7513.511621744569, 7558.433117516099, 7572.39420846342, 7593.734219302298, 7991.72028978983, 8075.192480751878, 8090.919090809017, 8111.553864537507, 8124.512529248264, 4626.629869610356, 4681.344611754425, 4684.0866031123605, 4697.906041879211, 4704.741239231738, 6046.848828779316, 7406.000189974285, 7435.144958329862, 7682.692692292246, 7698.307152356315, 7368.226336234618, 7384.040074790243, 7401.259874012771, 7403.037605111516, 7424.851502970018, 7337.449691273283, 7341.045664063638, 7360.300771426706, 7363.300486453778, 7395.445341599294, 6971.337722916315, 7205.279472052753, 7215.232268094351, 7216.3505284524645, 7216.89174662391, 7305.086864141982, 7373.0, 7382.2617738225745, 7408.111026676834, 7419.0, 6870.62211578348, 7097.858042839218, 7155.642217888981, 7209.134903811576, 7247.094113235846], "value": 5256.626417902105, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [559.8460423383569, 650.2911826109541, 718.2171433137881, 724.2467833453205, 751.8834580639996, 15062.322195501201, 15212.0, 15269.00751451155, 15412.537623871287, 15523.767143492843, 11736.53053877846, 13828.585142445743, 14038.157710537374, 14250.857491425082, 14286.357113929873, 746.3618606091795, 886.902440731519, 895.9865602015959, 908.309684639672, 908.7728067983004, 879.9780005499882, 926.4719110107242, 931.8835145606799, 939.7885475767931, 941.1059493481187, 818.7380038387719, 829.365535365443, 959.5490119643756, 985.4629227071264, 987.0820137272334, 949.0888746803078, 970.2286682087757, 973.0, 978.6819283732815, 979.6375341123794, 706.2725392845377, 709.7870638808389, 724.6847621284758, 730.3901242462497, 735.7608777147449, 14915.925420372938, 15043.097414155185, 15153.696926061417, 15159.545213643609, 15202.771958420633, 12861.164024338388, 12921.418536165895, 12952.87047129526, 13092.803607945889, 13223.73552528944, 9882.160016398615, 10037.799244015152, 10277.79444411115, 10311.948440257825, 10342.224333175041, 3472.7048200902955, 3493.4934434506854, 3528.2237907660274, 3567.839447224881, 3578.570571531433, 80927.97680057987, 81484.0, 82319.0, 82426.9999999994, 82712.58643706742, 9362.0, 9596.90403095974, 9622.855657165148, 9789.118979291756, 10048.397096174107, 22349.88250587464, 22670.65994010091, 22957.885210574175, 22965.655515167287, 22969.77030229676, 7354.448416368794, 7601.619919004028, 7606.9619651901385, 7615.276548727862, 7733.613319334123, 3397.8301084945656, 4023.0, 4083.1017176221185, 4085.9795701021303, 4091.488563929509, 3372.5952885653387, 3415.0534195508817, 3419.2477654916374, 3430.1596108952967, 3432.056184549219, 663.5952069237761, 675.7432175773175, 675.7634827810227, 678.7183318922591, 680.1498127340824, 492.75116066386244, 536.8630999095284, 580.4659713063907, 612.1116356041662, 613.0803794308537, 7891.802704932308, 7978.800529986681, 8119.512829230265, 8263.297619702273, 8340.68887544507, 479.3959610890234, 570.6804189653807, 596.5406636889571, 610.5237914426755, 629.6159342800888, 3749.1939233064477, 3875.5736868944396, 3883.8446462141765, 3888.2806680764147, 3924.6075392460525, 84762.15237847666, 92702.53648731713, 93456.0, 94480.52759736158, 94651.0, 78837.42325153465, 79146.0, 79576.60211698904, 80957.59521202356, 80971.0, 69713.0, 70426.64786676034, 71616.64191679009, 72432.63783681048, 72601.6369918147, 8126.146754590683, 8360.122187170502, 8367.28878045373, 8542.44474109181, 8624.439411438236, 37128.0, 37733.24533509259, 47958.28062578994, 48962.0, 50833.0, 63366.68316658387, 63441.0, 63859.0, 64163.0, 67373.32626673866, 10543.20925930543, 10626.309289896131, 11282.605108821152, 11481.36852473122, 11958.820617690568, 10116.291859570054, 11772.823407649063, 11959.222650527689, 12043.638690839289, 12446.190997585129, 11479.713007174887, 11480.138989575982, 11487.94256028748, 11568.0, 11821.940890295493, 11043.503042362952, 11077.169212308949, 11199.328040317607, 11646.70883227926, 12224.205426647239, 9368.859467108136, 11328.207025508136, 11428.657140285804, 11435.428228588704, 11464.4267786612, 7880.960595196987, 8262.339012879076, 9634.325597208128, 9701.417914924845, 10026.248031397528, 9711.465869377253, 9716.271279654196, 9797.804043918939, 9798.0, 9835.409875407498], "value": 6879.435438160706, "unit": "score"}], "name": "dromaeo_css", "value": 7367.756912824077, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 119.0, 108.0, 119.0, 122.0, 117.0, 108.0, 115.0, 116.0, 114.0], "value": 115.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 122.0, 152.0, 189.0, 108.0, 180.0, 175.0, 154.0, 161.0, 121.0], "value": 151.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 194.0, 198.0, 226.0, 228.0, 198.0, 194.0, 292.0, 247.0, 198.0], "value": 217.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 81.0, 74.0, 142.0, 55.0, 55.0, 56.0, 66.0, 118.0, 59.0], "value": 77.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 82.0, 128.0, 76.0, 80.0, 86.0, 139.0, 121.0, 119.0, 150.0], "value": 106.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 88.0, 86.0, 90.0, 86.0, 88.0, 86.0, 89.0, 85.0], "value": 87.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 152.0, 94.0, 95.0, 94.0, 97.0, 94.0, 107.0, 99.0, 154.0], "value": 112.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 96.0, 90.0, 92.0, 94.0, 89.0, 91.0, 93.0, 91.0, 91.0], "value": 91.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 66.0, 65.0, 65.0, 67.0, 67.0, 66.0, 81.0, 65.0, 61.0], "value": 66.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 66.0, 70.0, 73.0, 81.0, 72.0, 69.0, 72.0, 68.0, 72.0], "value": 71.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 83.0, 80.0, 80.0, 81.0, 80.0, 82.0, 83.0, 84.0, 87.0], "value": 82.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 126.0, 132.0, 128.0, 131.0, 130.0, 129.0, 126.0, 150.0, 144.0], "value": 132.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [225.0, 220.0, 164.0, 172.0, 206.0, 170.0, 173.0, 218.0, 200.0, 222.0], "value": 197.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 68.0, 67.0, 85.0, 67.0, 89.0, 66.0, 55.0, 58.0], "value": 69.0, "unit": "score"}], "name": "kraken", "value": 1576.8000000000002, "alertThreshold": 2.0}]} 02:48:35 INFO - Return code: 0 02:48:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 02:48:35 ERROR - # TBPL SUCCESS # 02:48:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:48:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:48:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:48:35 INFO - 2016-05-10 02:48:35.964 screenresolution[1956:14815] starting screenresolution argv=screenresolution get 02:48:35 INFO - 2016-05-10 02:48:35.966 screenresolution[1956:14815] Display 0: 1600x1200x32@60 02:48:35 INFO - 2016-05-10 02:48:35.972 screenresolution[1957:14818] starting screenresolution argv=screenresolution list 02:48:35 INFO - Available Modes on Display 0 02:48:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:48:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:48:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:48:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:48:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:48:36 INFO - Intel Iris: 02:48:36 INFO - Chipset Model: Intel Iris 02:48:36 INFO - Type: GPU 02:48:36 INFO - Bus: Built-In 02:48:36 INFO - VRAM (Dynamic, Max): 1536 MB 02:48:36 INFO - Vendor: Intel (0x8086) 02:48:36 INFO - Device ID: 0x0a2e 02:48:36 INFO - Revision ID: 0x0009 02:48:36 INFO - Displays: 02:48:36 INFO - Display: 02:48:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:48:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:48:36 INFO - Display Serial Number: 1600x1200 60 02:48:36 INFO - Main Display: Yes 02:48:36 INFO - Mirror: Off 02:48:36 INFO - Online: Yes 02:48:36 INFO - Rotation: Supported 02:48:36 INFO - Return code: 0 02:48:36 INFO - Running post-action listener: _resource_record_post_action 02:48:36 INFO - Running post-run listener: _resource_record_post_run 02:48:37 INFO - Total resource usage - Wall time: 1205s; CPU: 53.0%; Read bytes: 1019904; Write bytes: 656549376; Read time: 16; Write time: 1891 02:48:37 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 354146816; Write bytes: 349980160; Read time: 27911; Write time: 671 02:48:37 INFO - run-tests - Wall time: 1174s; CPU: 53.0%; Read bytes: 1019904; Write bytes: 298649600; Read time: 16; Write time: 1184 02:48:37 INFO - Running post-run listener: _upload_blobber_files 02:48:37 INFO - Blob upload gear active. 02:48:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:48:37 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 02:48:37 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'] 02:48:37 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 02:48:37 INFO - (blobuploader) - INFO - Open directory for files ... 02:48:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:48:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:48:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:48:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:48:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:48:39 INFO - (blobuploader) - INFO - Done attempting. 02:48:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:48:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:48:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:48:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:48:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:48:40 INFO - (blobuploader) - INFO - Done attempting. 02:48:40 INFO - (blobuploader) - INFO - Iteration through files over. 02:48:40 INFO - Return code: 0 02:48:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:48:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:48:40 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a50270ae44eef6e008d5a85eb6d651dd0fd7766575e79753a3d340f3c2fd8f3d8638fd13d87fbc0f6821144ad2914f61e520adae39d22271a41091d313dc3974", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e4a90f552e85be32ba6f276a9226f5294ce55245e8395fafb3f5ca6b8dbc494aca5e9c60c3c50b4e24854e30bf775d86bc210a08b466c3b7e919653f842cd7ea"} 02:48:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:48:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:48:40 INFO - Contents: 02:48:40 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a50270ae44eef6e008d5a85eb6d651dd0fd7766575e79753a3d340f3c2fd8f3d8638fd13d87fbc0f6821144ad2914f61e520adae39d22271a41091d313dc3974", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e4a90f552e85be32ba6f276a9226f5294ce55245e8395fafb3f5ca6b8dbc494aca5e9c60c3c50b4e24854e30bf775d86bc210a08b466c3b7e919653f842cd7ea"} 02:48:40 INFO - Running post-run listener: copy_logs_to_upload_dir 02:48:40 INFO - Copying logs to upload dir... 02:48:40 INFO - mkdir: /builds/slave/test/build/upload/logs 02:48:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.923130 ========= master_lag: 1.97 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-05-10 02:48:42.623960) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-10 02:48:42.628734) ========= 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.dVULbzvWip/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.xEQdmiXgSU/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a50270ae44eef6e008d5a85eb6d651dd0fd7766575e79753a3d340f3c2fd8f3d8638fd13d87fbc0f6821144ad2914f61e520adae39d22271a41091d313dc3974", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e4a90f552e85be32ba6f276a9226f5294ce55245e8395fafb3f5ca6b8dbc494aca5e9c60c3c50b4e24854e30bf775d86bc210a08b466c3b7e919653f842cd7ea"} build_url:https://queue.taskcluster.net/v1/task/aOXVkNIVT7isTwgD8DrGYA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011223 build_url: 'https://queue.taskcluster.net/v1/task/aOXVkNIVT7isTwgD8DrGYA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a50270ae44eef6e008d5a85eb6d651dd0fd7766575e79753a3d340f3c2fd8f3d8638fd13d87fbc0f6821144ad2914f61e520adae39d22271a41091d313dc3974", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e4a90f552e85be32ba6f276a9226f5294ce55245e8395fafb3f5ca6b8dbc494aca5e9c60c3c50b4e24854e30bf775d86bc210a08b466c3b7e919653f842cd7ea"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-10 02:48:42.658921) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 4 secs) (at 2016-05-10 02:48:42.659309) ========= 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.dVULbzvWip/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.xEQdmiXgSU/Listeners TMPDIR=/var/folders/pq/9wq8k9b96lz0dzpzk0df56pw00000w/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.005156 ========= master_lag: 4.18 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 4 secs) (at 2016-05-10 02:48:46.842924) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-10 02:48:46.843227) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-10 02:48:46.848656) ========= ========= Total master_lag: 6.24 =========