builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0164 starttime: 1464635918.29 results: success (0) buildid: 20160530112445 builduid: 47162b93c1e4433fae6500d2eaafc379 revision: e3d15342ba84ae49b8f8b0350a9d92bcc3c38b5f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 12:18:38.289790) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 12:18:38.290186) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 12:18:38.290539) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 12:18:38.416590) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 12:18:38.416954) ========= 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.eZEdY1ZsC9/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.gTjW51ICda/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-30 12:18:38-- 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% 28.6M=0s 2016-05-30 12:18:38 (28.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.116333 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 12:18:38.555337) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 12:18:38.555621) ========= 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.eZEdY1ZsC9/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.gTjW51ICda/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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.028057 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 12:18:38.605168) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-30 12:18:38.605473) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev e3d15342ba84ae49b8f8b0350a9d92bcc3c38b5f --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 e3d15342ba84ae49b8f8b0350a9d92bcc3c38b5f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.eZEdY1ZsC9/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.gTjW51ICda/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-30 12:18:38,681 truncating revision to first 12 chars 2016-05-30 12:18:38,681 Setting DEBUG logging. 2016-05-30 12:18:38,681 attempt 1/10 2016-05-30 12:18:38,682 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/e3d15342ba84?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-30 12:18:39,920 unpacking tar archive at: mozilla-aurora-e3d15342ba84/testing/mozharness/ program finished with exit code 0 elapsedTime=1.497973 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-30 12:18:40.123300) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 12:18:40.123627) ========= script_repo_revision: e3d15342ba84ae49b8f8b0350a9d92bcc3c38b5f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 12:18:40.124104) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 12:18:40.124406) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 12:18:40.141278) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2016-05-30 12:18:40.141582) ========= /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.eZEdY1ZsC9/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.gTjW51ICda/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 12:18:40 INFO - MultiFileLogger online at 20160530 12:18:40 in /builds/slave/test 12:18:40 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 12:18:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:18:40 INFO - {'append_to_log': False, 12:18:40 INFO - 'base_work_dir': '/builds/slave/test', 12:18:40 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 12:18:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:18:40 INFO - 'branch': 'Mozilla-Aurora', 12:18:40 INFO - 'buildbot_json_path': 'buildprops.json', 12:18:40 INFO - 'config_files': ('talos/mac_config.py',), 12:18:40 INFO - 'default_actions': ('clobber', 12:18:40 INFO - 'read-buildbot-config', 12:18:40 INFO - 'download-and-extract', 12:18:40 INFO - 'populate-webroot', 12:18:40 INFO - 'create-virtualenv', 12:18:40 INFO - 'install', 12:18:40 INFO - 'run-tests'), 12:18:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:18:40 INFO - 'download_minidump_stackwalk': True, 12:18:40 INFO - 'download_symbols': 'ondemand', 12:18:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:18:40 INFO - 'tooltool.py': '/tools/tooltool.py', 12:18:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:18:40 INFO - '/tools/misc-python/virtualenv.py')}, 12:18:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:18:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:18:40 INFO - 'installer_path': 'installer.exe', 12:18:40 INFO - 'log_level': 'info', 12:18:40 INFO - 'log_name': 'talos', 12:18:40 INFO - 'log_to_console': True, 12:18:40 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:18:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:18:40 INFO - 'opt_config_files': (), 12:18:40 INFO - 'pip_index': False, 12:18:40 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:18:40 INFO - 'cmd': ('bash', 12:18:40 INFO - '-c', 12:18:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:18:40 INFO - 'enabled': True, 12:18:40 INFO - 'halt_on_failure': False, 12:18:40 INFO - 'name': 'check_screen_resolution'},), 12:18:40 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:18:40 INFO - 'cmd': ('bash', 12:18:40 INFO - '-c', 12:18:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:18:40 INFO - 'enabled': True, 12:18:40 INFO - 'halt_on_failure': False, 12:18:40 INFO - 'name': 'check_screen_resolution'},), 12:18:40 INFO - 'run_cmd_checks_enabled': True, 12:18:40 INFO - 'sps_profile': False, 12:18:40 INFO - 'sps_profile_interval': 0, 12:18:40 INFO - 'suite': 'dromaeojs-e10s', 12:18:40 INFO - 'system_bits': '32', 12:18:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:18:40 INFO - 'title': 't-yosemite-r7-0164', 12:18:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:18:40 INFO - 'use_talos_json': True, 12:18:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:18:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:18:40 INFO - 'work_dir': 'build'} 12:18:40 INFO - ##### 12:18:40 INFO - ##### Running clobber step. 12:18:40 INFO - ##### 12:18:40 INFO - Running pre-action listener: _resource_record_pre_action 12:18:40 INFO - Running main action method: clobber 12:18:40 INFO - rmtree: /builds/slave/test/build 12:18:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:18:42 INFO - Running post-action listener: _resource_record_post_action 12:18:42 INFO - ##### 12:18:42 INFO - ##### Running read-buildbot-config step. 12:18:42 INFO - ##### 12:18:42 INFO - Running pre-action listener: _resource_record_pre_action 12:18:42 INFO - Running main action method: read_buildbot_config 12:18:42 INFO - Using buildbot properties: 12:18:42 INFO - { 12:18:42 INFO - "project": "", 12:18:42 INFO - "product": "firefox", 12:18:42 INFO - "script_repo_revision": "production", 12:18:42 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 12:18:42 INFO - "repository": "", 12:18:42 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 12:18:42 INFO - "buildid": "20160530112445", 12:18:42 INFO - "pgo_build": "False", 12:18:42 INFO - "basedir": "/builds/slave/test", 12:18:42 INFO - "buildnumber": 344, 12:18:42 INFO - "slavename": "t-yosemite-r7-0164", 12:18:42 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 12:18:42 INFO - "platform": "yosemite_r7", 12:18:42 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 12:18:42 INFO - "branch": "mozilla-aurora", 12:18:42 INFO - "repo_path": "releases/mozilla-aurora", 12:18:42 INFO - "revision": "e3d15342ba84ae49b8f8b0350a9d92bcc3c38b5f", 12:18:42 INFO - "stage_platform": "macosx64", 12:18:42 INFO - "builduid": "47162b93c1e4433fae6500d2eaafc379", 12:18:42 INFO - "slavebuilddir": "test" 12:18:42 INFO - } 12:18:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/SxAtSq2hSWKDPgiN5vy_4Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 12:18:42 INFO - Running post-action listener: _resource_record_post_action 12:18:42 INFO - ##### 12:18:42 INFO - ##### Running download-and-extract step. 12:18:42 INFO - ##### 12:18:42 INFO - Running pre-action listener: _resource_record_pre_action 12:18:42 INFO - Running main action method: download_and_extract 12:18:42 INFO - mkdir: /builds/slave/test/build/tests 12:18:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:18:42 INFO - https://queue.taskcluster.net/v1/task/SxAtSq2hSWKDPgiN5vy_4Q/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 12:18:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SxAtSq2hSWKDPgiN5vy_4Q/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 12:18:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SxAtSq2hSWKDPgiN5vy_4Q/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 12:18:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SxAtSq2hSWKDPgiN5vy_4Q/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 12:18:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SxAtSq2hSWKDPgiN5vy_4Q/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 12:18:47 INFO - Downloaded 1199 bytes. 12:18:47 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 12:18:47 INFO - Using the following test package requirements: 12:18:47 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 12:18:47 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:18:47 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 12:18:47 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:18:47 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 12:18:47 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:18:47 INFO - u'jsshell-mac.zip'], 12:18:47 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:18:47 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 12:18:47 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 12:18:47 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:18:47 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 12:18:47 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:18:47 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 12:18:47 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:18:47 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 12:18:47 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:18:47 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 12:18:47 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 12:18:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:18:47 INFO - https://queue.taskcluster.net/v1/task/SxAtSq2hSWKDPgiN5vy_4Q/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:18:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SxAtSq2hSWKDPgiN5vy_4Q/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 12:18:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SxAtSq2hSWKDPgiN5vy_4Q/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 12:18:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SxAtSq2hSWKDPgiN5vy_4Q/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 12:18:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SxAtSq2hSWKDPgiN5vy_4Q/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 12:18:59 INFO - Downloaded 18463814 bytes. 12:18:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:18:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 12:19:02 INFO - Return code: 0 12:19:02 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 12:19: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')]} 12:19:02 INFO - https://queue.taskcluster.net/v1/task/SxAtSq2hSWKDPgiN5vy_4Q/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:19:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SxAtSq2hSWKDPgiN5vy_4Q/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 12:19:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SxAtSq2hSWKDPgiN5vy_4Q/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 12:19:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SxAtSq2hSWKDPgiN5vy_4Q/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 12:19:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SxAtSq2hSWKDPgiN5vy_4Q/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 12:19:08 INFO - Downloaded 18463814 bytes. 12:19:08 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:19:08 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 12:19:11 INFO - Return code: 0 12:19:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:19:11 INFO - https://queue.taskcluster.net/v1/task/SxAtSq2hSWKDPgiN5vy_4Q/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 12:19:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SxAtSq2hSWKDPgiN5vy_4Q/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 12:19:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SxAtSq2hSWKDPgiN5vy_4Q/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 12:19:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SxAtSq2hSWKDPgiN5vy_4Q/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 12:19:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SxAtSq2hSWKDPgiN5vy_4Q/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 12:19:12 INFO - Downloaded 10840145 bytes. 12:19:12 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:19:12 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 12:19:12 INFO - Return code: 0 12:19: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')]} 12:19:12 INFO - https://queue.taskcluster.net/v1/task/SxAtSq2hSWKDPgiN5vy_4Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 12:19:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SxAtSq2hSWKDPgiN5vy_4Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 12:19:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SxAtSq2hSWKDPgiN5vy_4Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 12:19:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SxAtSq2hSWKDPgiN5vy_4Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 12:19:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SxAtSq2hSWKDPgiN5vy_4Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 12:19:17 INFO - Downloaded 114132760 bytes. 12:19:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SxAtSq2hSWKDPgiN5vy_4Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 12:19:17 INFO - mkdir: /builds/slave/test/properties 12:19:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:19:17 INFO - Writing to file /builds/slave/test/properties/build_url 12:19:17 INFO - Contents: 12:19:17 INFO - build_url:https://queue.taskcluster.net/v1/task/SxAtSq2hSWKDPgiN5vy_4Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 12:19:19 INFO - Running post-action listener: _resource_record_post_action 12:19:19 INFO - Running post-action listener: set_extra_try_arguments 12:19:19 INFO - ##### 12:19:19 INFO - ##### Running populate-webroot step. 12:19:19 INFO - ##### 12:19:19 INFO - Running pre-action listener: _resource_record_pre_action 12:19:19 INFO - Running main action method: populate_webroot 12:19:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:19:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:19:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:19:19 INFO - '--tppagecycles', 12:19:19 INFO - '7'], 12:19:19 INFO - 'tests': ['tsvgm']}}, 12:19:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:19:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:19:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:19:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:19:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:19:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:19:19 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:19:19 INFO - 'talos_options': ['--disable-e10s'], 12:19:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:19:19 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:19:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:19:19 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:19:19 INFO - 'talos_options': ['--disable-e10s'], 12:19:19 INFO - 'tests': ['damp', 'tps']}, 12:19:19 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:19:19 INFO - 'tests': ['damp', 'tps']}, 12:19:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:19:19 INFO - 'tests': ['dromaeo_dom']}, 12:19:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:19:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:19:19 INFO - 'tests': ['a11yr', 12:19:19 INFO - 'ts_paint', 12:19:19 INFO - 'tpaint', 12:19:19 INFO - 'sessionrestore', 12:19:19 INFO - 'sessionrestore_no_auto_restore', 12:19:19 INFO - 'tabpaint']}, 12:19:19 INFO - 'other-e10s': {'tests': ['a11yr', 12:19:19 INFO - 'ts_paint', 12:19:19 INFO - 'tpaint', 12:19:19 INFO - 'sessionrestore', 12:19:19 INFO - 'sessionrestore_no_auto_restore', 12:19:19 INFO - 'tabpaint']}, 12:19:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:19:19 INFO - 'ts_paint', 12:19:19 INFO - 'tpaint', 12:19:19 INFO - 'sessionrestore', 12:19:19 INFO - 'sessionrestore_no_auto_restore', 12:19:19 INFO - 'tabpaint']}, 12:19:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:19:19 INFO - 'ts_paint', 12:19:19 INFO - 'tpaint', 12:19:19 INFO - 'sessionrestore', 12:19:19 INFO - 'sessionrestore_no_auto_restore', 12:19:19 INFO - 'tabpaint']}, 12:19:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:19:19 INFO - 'tests': ['a11yr', 12:19:19 INFO - 'ts_paint', 12:19:19 INFO - 'tpaint', 12:19:19 INFO - 'sessionrestore', 12:19:19 INFO - 'sessionrestore_no_auto_restore', 12:19:19 INFO - 'tabpaint']}, 12:19:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:19:19 INFO - 'tests': ['a11yr', 12:19:19 INFO - 'ts_paint', 12:19:19 INFO - 'tpaint', 12:19:19 INFO - 'sessionrestore', 12:19:19 INFO - 'sessionrestore_no_auto_restore', 12:19:19 INFO - 'tabpaint']}, 12:19:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:19:19 INFO - 'tests': ['tsvgx', 12:19:19 INFO - 'tsvgr_opacity', 12:19:19 INFO - 'tart', 12:19:19 INFO - 'tscrollx', 12:19:19 INFO - 'cart']}, 12:19:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:19:19 INFO - 'tsvgr_opacity', 12:19:19 INFO - 'tart', 12:19:19 INFO - 'tscrollx', 12:19:19 INFO - 'cart']}, 12:19:19 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:19:19 INFO - 'talos_options': ['--disable-e10s'], 12:19:19 INFO - 'tests': ['tp5o']}, 12:19:19 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:19:19 INFO - 'tests': ['tp5o']}, 12:19:19 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:19:19 INFO - 'talos_options': ['--disable-e10s', 12:19:19 INFO - '--xperf_path', 12:19:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:19:19 INFO - 'tests': ['tp5n']}, 12:19:19 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:19:19 INFO - 'talos_options': ['--xperf_path', 12:19:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:19:19 INFO - 'tests': ['tp5n']}}, 12:19:19 INFO - 'talos.zip': {'path': '', 12:19:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:19:19 INFO - Running post-action listener: _resource_record_post_action 12:19:19 INFO - ##### 12:19:19 INFO - ##### Running create-virtualenv step. 12:19:19 INFO - ##### 12:19:19 INFO - Running pre-action listener: _resource_record_pre_action 12:19:19 INFO - Running main action method: create_virtualenv 12:19:19 INFO - Creating virtualenv /builds/slave/test/build/venv 12:19:19 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:19:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:19:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:19:19 INFO - Using real prefix '/tools/python27' 12:19:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:19:19 INFO - Installing distribute.............................................................................................................................................................................................done. 12:19:22 INFO - Installing pip.................done. 12:19:22 INFO - Return code: 0 12:19:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:19: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')]} 12:19:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19: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')]} 12:19:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109560a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109570420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5b9cbd920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1093ffe00>, '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 0x109268db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1093fab20>, '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.eZEdY1ZsC9/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.gTjW51ICda/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:19: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', 'pip>=1.5'] in /builds/slave/test/build 12:19: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 pip>=1.5 12:19:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eZEdY1ZsC9/Render', 12:19:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:22 INFO - 'HOME': '/Users/cltbld', 12:19:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:22 INFO - 'LOGNAME': 'cltbld', 12:19:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:22 INFO - 'MOZ_NO_REMOTE': '1', 12:19:22 INFO - 'NO_EM_RESTART': '1', 12:19:22 INFO - 'PAGER': '/bin/cat', 12:19:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:22 INFO - 'PWD': '/builds/slave/test', 12:19:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:22 INFO - 'SHELL': '/bin/bash', 12:19:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gTjW51ICda/Listeners', 12:19:22 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 12:19:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:22 INFO - 'USER': 'cltbld', 12:19:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:22 INFO - 'XPC_FLAGS': '0x0', 12:19:22 INFO - 'XPC_SERVICE_NAME': '0', 12:19:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:23 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 12:19:23 INFO - Cleaning up... 12:19:23 INFO - Return code: 0 12:19:23 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 12:19:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:19:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:19:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:23 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 0x109560a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109570420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5b9cbd920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1093ffe00>, '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 0x109268db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1093fab20>, '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.eZEdY1ZsC9/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.gTjW51ICda/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:19:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 12:19:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 12:19:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eZEdY1ZsC9/Render', 12:19:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:23 INFO - 'HOME': '/Users/cltbld', 12:19:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:23 INFO - 'LOGNAME': 'cltbld', 12:19:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:23 INFO - 'MOZ_NO_REMOTE': '1', 12:19:23 INFO - 'NO_EM_RESTART': '1', 12:19:23 INFO - 'PAGER': '/bin/cat', 12:19:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:23 INFO - 'PWD': '/builds/slave/test', 12:19:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:23 INFO - 'SHELL': '/bin/bash', 12:19:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gTjW51ICda/Listeners', 12:19:23 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 12:19:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:23 INFO - 'USER': 'cltbld', 12:19:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:23 INFO - 'XPC_FLAGS': '0x0', 12:19:23 INFO - 'XPC_SERVICE_NAME': '0', 12:19:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:23 INFO - Downloading/unpacking psutil>=0.7.1 12:19:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:19:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:19:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:19:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:19:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:19:26 INFO - Installing collected packages: psutil 12:19:26 INFO - Running setup.py install for psutil 12:19:26 INFO - building 'psutil._psutil_osx' extension 12:19:26 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 12:19:27 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 12:19:27 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 12:19:27 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 12:19:27 INFO - building 'psutil._psutil_posix' extension 12:19:27 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 12:19:27 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:19:27 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:19:27 INFO - ^ 12:19:27 INFO - 1 warning generated. 12:19:27 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 12:19:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:19:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:19:28 INFO - Successfully installed psutil 12:19:28 INFO - Cleaning up... 12:19:28 INFO - Return code: 0 12:19:28 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 12:19:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:19:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:19:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:28 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 0x109560a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109570420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5b9cbd920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1093ffe00>, '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 0x109268db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1093fab20>, '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.eZEdY1ZsC9/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.gTjW51ICda/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:19:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 12:19:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 12:19:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eZEdY1ZsC9/Render', 12:19:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:28 INFO - 'HOME': '/Users/cltbld', 12:19:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:28 INFO - 'LOGNAME': 'cltbld', 12:19:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:28 INFO - 'MOZ_NO_REMOTE': '1', 12:19:28 INFO - 'NO_EM_RESTART': '1', 12:19:28 INFO - 'PAGER': '/bin/cat', 12:19:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:28 INFO - 'PWD': '/builds/slave/test', 12:19:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:28 INFO - 'SHELL': '/bin/bash', 12:19:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gTjW51ICda/Listeners', 12:19:28 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 12:19:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:28 INFO - 'USER': 'cltbld', 12:19:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:28 INFO - 'XPC_FLAGS': '0x0', 12:19:28 INFO - 'XPC_SERVICE_NAME': '0', 12:19:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:28 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 12:19: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 12:19: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 12:19: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 12:19: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 12:19: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 12:19: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 12:19:30 INFO - Downloading mozsystemmonitor-0.0.tar.gz 12:19:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:19:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 12:19:30 INFO - Installing collected packages: mozsystemmonitor 12:19:30 INFO - Running setup.py install for mozsystemmonitor 12:19:30 INFO - Successfully installed mozsystemmonitor 12:19:30 INFO - Cleaning up... 12:19:31 INFO - Return code: 0 12:19:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:19:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:19:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:19:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:31 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 0x109560a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109570420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5b9cbd920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1093ffe00>, '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 0x109268db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1093fab20>, '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.eZEdY1ZsC9/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.gTjW51ICda/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:19:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:19:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:19:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eZEdY1ZsC9/Render', 12:19:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:31 INFO - 'HOME': '/Users/cltbld', 12:19:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:31 INFO - 'LOGNAME': 'cltbld', 12:19:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:31 INFO - 'MOZ_NO_REMOTE': '1', 12:19:31 INFO - 'NO_EM_RESTART': '1', 12:19:31 INFO - 'PAGER': '/bin/cat', 12:19:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:31 INFO - 'PWD': '/builds/slave/test', 12:19:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:31 INFO - 'SHELL': '/bin/bash', 12:19:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gTjW51ICda/Listeners', 12:19:31 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 12:19:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:31 INFO - 'USER': 'cltbld', 12:19:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:31 INFO - 'XPC_FLAGS': '0x0', 12:19:31 INFO - 'XPC_SERVICE_NAME': '0', 12:19:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:31 INFO - Downloading/unpacking blobuploader==1.2.4 12:19:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:19:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:19:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:33 INFO - Downloading blobuploader-1.2.4.tar.gz 12:19:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:19:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:19:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:19:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:19:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:19:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:19:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:19:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:19:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:34 INFO - Downloading docopt-0.6.1.tar.gz 12:19:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:19:35 INFO - Installing collected packages: blobuploader, requests, docopt 12:19:35 INFO - Running setup.py install for blobuploader 12:19:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:19:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:19:35 INFO - Running setup.py install for requests 12:19:35 INFO - Running setup.py install for docopt 12:19:35 INFO - Successfully installed blobuploader requests docopt 12:19:35 INFO - Cleaning up... 12:19:35 INFO - Return code: 0 12:19:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:19:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:19:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:19:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:35 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 0x109560a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109570420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5b9cbd920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1093ffe00>, '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 0x109268db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1093fab20>, '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.eZEdY1ZsC9/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.gTjW51ICda/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:19:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:19:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:19:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eZEdY1ZsC9/Render', 12:19:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:35 INFO - 'HOME': '/Users/cltbld', 12:19:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:35 INFO - 'LOGNAME': 'cltbld', 12:19:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:35 INFO - 'MOZ_NO_REMOTE': '1', 12:19:35 INFO - 'NO_EM_RESTART': '1', 12:19:35 INFO - 'PAGER': '/bin/cat', 12:19:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:35 INFO - 'PWD': '/builds/slave/test', 12:19:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:35 INFO - 'SHELL': '/bin/bash', 12:19:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gTjW51ICda/Listeners', 12:19:35 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 12:19:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:35 INFO - 'USER': 'cltbld', 12:19:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:35 INFO - 'XPC_FLAGS': '0x0', 12:19:35 INFO - 'XPC_SERVICE_NAME': '0', 12:19:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:19:36 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-feOniu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:19:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:19:36 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-EXHpBg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:19:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:19:36 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-M4vw38-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:19:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:19:36 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-Qv9QI3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:19:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:19:36 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-NEGEjQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:19:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:19:36 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-V4iNzJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:19:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:19:36 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-5eslUu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:19:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:19:36 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-7pfKXS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:19:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:19:36 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-vs37f6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:19:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:19:37 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-bOFcpB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:19:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:19:37 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-ZitF9n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:19:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:19:37 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-EiF2ba-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:19:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:19:37 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-nXPVGo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:19:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:19:37 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-_W7KKG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:19:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:19:37 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-2db7rr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:19:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:19:37 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-GbAXnI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:19:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:19:37 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-jG2sw6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:19:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:19:37 INFO - Running setup.py install for manifestparser 12:19:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:19:38 INFO - Running setup.py install for mozcrash 12:19:38 INFO - Running setup.py install for mozdebug 12:19:38 INFO - Running setup.py install for mozdevice 12:19:38 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:19:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:19:38 INFO - Running setup.py install for mozfile 12:19:38 INFO - Running setup.py install for mozhttpd 12:19:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:19:38 INFO - Running setup.py install for mozinfo 12:19:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:19:38 INFO - Running setup.py install for mozInstall 12:19:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:19:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:19:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:19:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:19:39 INFO - Running setup.py install for mozleak 12:19:39 INFO - Running setup.py install for mozlog 12:19:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:19:39 INFO - Running setup.py install for moznetwork 12:19:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:19:39 INFO - Running setup.py install for mozprocess 12:19:39 INFO - Running setup.py install for mozprofile 12:19:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:19:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:19:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:19:39 INFO - Running setup.py install for mozrunner 12:19:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:19:39 INFO - Running setup.py install for mozscreenshot 12:19:40 INFO - Running setup.py install for moztest 12:19:40 INFO - Running setup.py install for mozversion 12:19:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:19:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:19:40 INFO - Cleaning up... 12:19:40 INFO - Return code: 0 12:19:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:19:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:19:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:19:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:40 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 0x109560a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109570420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5b9cbd920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1093ffe00>, '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 0x109268db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1093fab20>, '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.eZEdY1ZsC9/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.gTjW51ICda/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:19:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:19:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:19:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eZEdY1ZsC9/Render', 12:19:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:40 INFO - 'HOME': '/Users/cltbld', 12:19:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:40 INFO - 'LOGNAME': 'cltbld', 12:19:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:40 INFO - 'MOZ_NO_REMOTE': '1', 12:19:40 INFO - 'NO_EM_RESTART': '1', 12:19:40 INFO - 'PAGER': '/bin/cat', 12:19:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:40 INFO - 'PWD': '/builds/slave/test', 12:19:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:40 INFO - 'SHELL': '/bin/bash', 12:19:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gTjW51ICda/Listeners', 12:19:40 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 12:19:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:40 INFO - 'USER': 'cltbld', 12:19:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:40 INFO - 'XPC_FLAGS': '0x0', 12:19:40 INFO - 'XPC_SERVICE_NAME': '0', 12:19:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:19:40 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-_DOJ69-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:19:40 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 12:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:19:40 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-sTCav3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:19:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:19:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:19:40 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-0pjvY_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:19:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:19:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:19:41 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-nZ1NBx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:19:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:19:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:19:41 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-152V2s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:19:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 12:19:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:19:41 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-rsdByt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:19:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 12:19:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:19:41 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-yMAQuj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:19:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 12:19:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:19:41 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-0gDKME-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:19:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 12:19:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:19:41 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-O53pqm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:19:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 12:19:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:19:41 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-J4bjGP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:19:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:19:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:19:41 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-XCnPH3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:19:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 12:19:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:19:41 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-xEhzZl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:19:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 12:19:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:19:41 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-8N1O_h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:19:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 12:19:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:19:42 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-VWK8Wp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:19:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 12:19:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:19:42 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-vChnFZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:19:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 12:19:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:19:42 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-wCvLRw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:19:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 12:19:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:19:42 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-Xlyv02-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:19:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 12:19:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:19:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:19:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:19:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:19:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:19:42 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:19:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:19:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:19:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:45 INFO - Downloading blessings-1.6.tar.gz 12:19:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:19:45 INFO - Installing collected packages: blessings 12:19:45 INFO - Running setup.py install for blessings 12:19:45 INFO - Successfully installed blessings 12:19:45 INFO - Cleaning up... 12:19:45 INFO - Return code: 0 12:19:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:19:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:19:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:19:45 INFO - Reading from file tmpfile_stdout 12:19:45 INFO - Current package versions: 12:19:45 INFO - blessings == 1.6 12:19:45 INFO - blobuploader == 1.2.4 12:19:45 INFO - docopt == 0.6.1 12:19:45 INFO - manifestparser == 1.1 12:19:45 INFO - mozInstall == 1.12 12:19:45 INFO - mozcrash == 0.17 12:19:45 INFO - mozdebug == 0.1 12:19:45 INFO - mozdevice == 0.48 12:19:45 INFO - mozfile == 1.2 12:19:45 INFO - mozhttpd == 0.7 12:19:45 INFO - mozinfo == 0.9 12:19:45 INFO - mozleak == 0.1 12:19:45 INFO - mozlog == 3.1 12:19:45 INFO - moznetwork == 0.27 12:19:45 INFO - mozprocess == 0.22 12:19:45 INFO - mozprofile == 0.28 12:19:45 INFO - mozrunner == 6.11 12:19:45 INFO - mozscreenshot == 0.1 12:19:45 INFO - mozsystemmonitor == 0.0 12:19:45 INFO - moztest == 0.7 12:19:45 INFO - mozversion == 1.4 12:19:45 INFO - psutil == 3.1.1 12:19:45 INFO - requests == 1.2.3 12:19:45 INFO - wsgiref == 0.1.2 12:19:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:19:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:19:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:19:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:45 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 0x109560a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109570420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5b9cbd920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1093ffe00>, '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 0x109268db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1093fab20>, '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.eZEdY1ZsC9/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.gTjW51ICda/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:19:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 12:19:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:19:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eZEdY1ZsC9/Render', 12:19:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:45 INFO - 'HOME': '/Users/cltbld', 12:19:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:45 INFO - 'LOGNAME': 'cltbld', 12:19:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:45 INFO - 'MOZ_NO_REMOTE': '1', 12:19:45 INFO - 'NO_EM_RESTART': '1', 12:19:45 INFO - 'PAGER': '/bin/cat', 12:19:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:45 INFO - 'PWD': '/builds/slave/test', 12:19:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:45 INFO - 'SHELL': '/bin/bash', 12:19:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gTjW51ICda/Listeners', 12:19:45 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 12:19:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:45 INFO - 'USER': 'cltbld', 12:19:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:45 INFO - 'XPC_FLAGS': '0x0', 12:19:45 INFO - 'XPC_SERVICE_NAME': '0', 12:19:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:19:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 12:19:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 12:19:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:19:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 12:19:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 12:19:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 12:19:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 12:19:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 12:19:46 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:19:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:19:46 INFO - Cleaning up... 12:19:46 INFO - Return code: 0 12:19:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:19:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:19:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:19:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109560a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109570420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5b9cbd920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1093ffe00>, '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 0x109268db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1093fab20>, '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.eZEdY1ZsC9/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.gTjW51ICda/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:19:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:19:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:19:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eZEdY1ZsC9/Render', 12:19:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:46 INFO - 'HOME': '/Users/cltbld', 12:19:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:46 INFO - 'LOGNAME': 'cltbld', 12:19:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:46 INFO - 'MOZ_NO_REMOTE': '1', 12:19:46 INFO - 'NO_EM_RESTART': '1', 12:19:46 INFO - 'PAGER': '/bin/cat', 12:19:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:46 INFO - 'PWD': '/builds/slave/test', 12:19:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:46 INFO - 'SHELL': '/bin/bash', 12:19:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gTjW51ICda/Listeners', 12:19:46 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 12:19:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:46 INFO - 'USER': 'cltbld', 12:19:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:46 INFO - 'XPC_FLAGS': '0x0', 12:19:46 INFO - 'XPC_SERVICE_NAME': '0', 12:19:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:46 INFO - Downloading/unpacking jsonschema 12:19:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:19:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:19:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:19:48 INFO - Downloading/unpacking functools32 (from jsonschema) 12:19:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:19:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:19:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:49 INFO - Downloading functools32-3.2.3-2.tar.gz 12:19:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:19:49 INFO - warning: no files found matching '*.txt' 12:19:49 INFO - no previously-included directories found matching 'build' 12:19:49 INFO - no previously-included directories found matching 'dist' 12:19:49 INFO - no previously-included directories found matching '.git*' 12:19:49 INFO - Installing collected packages: jsonschema, functools32 12:19:49 INFO - Running setup.py install for functools32 12:19:49 INFO - warning: no files found matching '*.txt' 12:19:49 INFO - no previously-included directories found matching 'build' 12:19:49 INFO - no previously-included directories found matching 'dist' 12:19:49 INFO - no previously-included directories found matching '.git*' 12:19:49 INFO - Successfully installed jsonschema functools32 12:19:49 INFO - Cleaning up... 12:19:49 INFO - Return code: 0 12:19:49 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 12:19:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:19:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:19:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:19:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109560a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109570420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5b9cbd920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1093ffe00>, '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 0x109268db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1093fab20>, '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.eZEdY1ZsC9/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.gTjW51ICda/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:19:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 12:19:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 12:19:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eZEdY1ZsC9/Render', 12:19:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:49 INFO - 'HOME': '/Users/cltbld', 12:19:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:49 INFO - 'LOGNAME': 'cltbld', 12:19:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:49 INFO - 'MOZ_NO_REMOTE': '1', 12:19:49 INFO - 'NO_EM_RESTART': '1', 12:19:49 INFO - 'PAGER': '/bin/cat', 12:19:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:49 INFO - 'PWD': '/builds/slave/test', 12:19:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:49 INFO - 'SHELL': '/bin/bash', 12:19:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gTjW51ICda/Listeners', 12:19:49 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 12:19:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:49 INFO - 'USER': 'cltbld', 12:19:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:49 INFO - 'XPC_FLAGS': '0x0', 12:19:49 INFO - 'XPC_SERVICE_NAME': '0', 12:19:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:49 INFO - Downloading/unpacking flake8 12:19:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:19:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:19:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:52 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 12:19:52 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 12:19:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:19:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:19:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:52 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 12:19:52 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 12:19:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:19:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:19:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:53 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 12:19:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:19:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:19:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 12:19:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:19:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:19:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:19:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:19:53 INFO - no previously-included directories found matching 'docs/_build' 12:19:53 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 12:19:53 INFO - Running setup.py install for pep8 12:19:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:19:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:19:53 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:19:53 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:19:53 INFO - no previously-included directories found matching 'docs/_build' 12:19:53 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 12:19:53 INFO - Successfully installed flake8 mccabe pyflakes pep8 12:19:53 INFO - Cleaning up... 12:19:53 INFO - Return code: 0 12:19:53 INFO - Running post-action listener: _resource_record_post_action 12:19:53 INFO - Running post-action listener: _start_resource_monitoring 12:19:53 INFO - Starting resource monitoring. 12:19:53 INFO - ##### 12:19:53 INFO - ##### Running install step. 12:19:53 INFO - ##### 12:19:53 INFO - Running pre-action listener: _resource_record_pre_action 12:19:53 INFO - Running main action method: install 12:19:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:19:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:19:54 INFO - Reading from file tmpfile_stdout 12:19:54 INFO - Detecting whether we're running mozinstall >=1.0... 12:19:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:19:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:19:54 INFO - Reading from file tmpfile_stdout 12:19:54 INFO - Output received: 12:19:54 INFO - Usage: mozinstall [options] installer 12:19:54 INFO - Options: 12:19:54 INFO - -h, --help show this help message and exit 12:19:54 INFO - -d DEST, --destination=DEST 12:19:54 INFO - Directory to install application into. [default: 12:19:54 INFO - "/builds/slave/test"] 12:19:54 INFO - --app=APP Application being installed. [default: firefox] 12:19:54 INFO - mkdir: /builds/slave/test/build/application 12:19:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:19:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 12:20:25 INFO - Reading from file tmpfile_stdout 12:20:25 INFO - Output received: 12:20:25 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 12:20:25 INFO - Running post-action listener: _resource_record_post_action 12:20:25 INFO - ##### 12:20:25 INFO - ##### Running run-tests step. 12:20:25 INFO - ##### 12:20:25 INFO - Running pre-action listener: _resource_record_pre_action 12:20:25 INFO - Running main action method: run_tests 12:20:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:20:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:20:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:20:25 INFO - 2016-05-30 12:20:25.909 screenresolution[1649:8180] starting screenresolution argv=screenresolution get 12:20:25 INFO - 2016-05-30 12:20:25.912 screenresolution[1649:8180] Display 0: 1600x1200x32@60 12:20:25 INFO - 2016-05-30 12:20:25.918 screenresolution[1650:8183] starting screenresolution argv=screenresolution list 12:20:25 INFO - Available Modes on Display 0 12:20:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:20:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:20:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:20:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:20:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:20:26 INFO - Intel Iris: 12:20:26 INFO - Chipset Model: Intel Iris 12:20:26 INFO - Type: GPU 12:20:26 INFO - Bus: Built-In 12:20:26 INFO - VRAM (Dynamic, Max): 1536 MB 12:20:26 INFO - Vendor: Intel (0x8086) 12:20:26 INFO - Device ID: 0x0a2e 12:20:26 INFO - Revision ID: 0x0009 12:20:26 INFO - Displays: 12:20:26 INFO - Display: 12:20:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:20:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:20:26 INFO - Display Serial Number: 1600x1200 60 12:20:26 INFO - Main Display: Yes 12:20:26 INFO - Mirror: Off 12:20:26 INFO - Online: Yes 12:20:26 INFO - Rotation: Supported 12:20:26 INFO - Return code: 0 12:20:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:20:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:20:26 INFO - Python 2.7.3 12:20:26 INFO - Return code: 0 12:20:26 INFO - grabbing minidump binary from tooltool 12:20: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')]} 12:20:26 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 0x1093ffe00>, '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 0x109268db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1093fab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:20:26 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 12:20:26 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 12:20:26 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:20:26 INFO - Return code: 0 12:20:26 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:20:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:20:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:20:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:20:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:20:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 12:20:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 12:20:27 INFO - Return code: 0 12:20:27 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/SxAtSq2hSWKDPgiN5vy_4Q/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0164', '--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 12:20:27 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/SxAtSq2hSWKDPgiN5vy_4Q/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0164 --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 12:20:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eZEdY1ZsC9/Render', 12:20:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:20:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:20:27 INFO - 'HOME': '/Users/cltbld', 12:20:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:20:27 INFO - 'LOGNAME': 'cltbld', 12:20:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:20:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:20:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:20:27 INFO - 'MOZ_NO_REMOTE': '1', 12:20:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:20:27 INFO - 'NO_EM_RESTART': '1', 12:20:27 INFO - 'PAGER': '/bin/cat', 12:20:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:20:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:20:27 INFO - 'PWD': '/builds/slave/test', 12:20:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:20:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:20:27 INFO - 'SHELL': '/bin/bash', 12:20:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gTjW51ICda/Listeners', 12:20:27 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 12:20:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:20:27 INFO - 'USER': 'cltbld', 12:20:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:20:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:20:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:20:27 INFO - 'XPC_FLAGS': '0x0', 12:20:27 INFO - 'XPC_SERVICE_NAME': '0', 12:20:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:20:27 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/SxAtSq2hSWKDPgiN5vy_4Q/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0164', '--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 12:20:27 INFO - mozversion application_buildid: 20160530112445 12:20:27 INFO - mozversion application_changeset: e3d15342ba84ae49b8f8b0350a9d92bcc3c38b5f 12:20:27 INFO - mozversion application_display_name: FirefoxDeveloperEdition 12:20:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:20:27 INFO - mozversion application_name: Firefox 12:20:27 INFO - mozversion application_remotingname: firefox-dev 12:20:27 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:20:27 INFO - mozversion application_vendor: Mozilla 12:20:27 INFO - mozversion application_version: 48.0a2 12:20:27 INFO - mozversion platform_buildid: 20160530112445 12:20:27 INFO - mozversion platform_changeset: e3d15342ba84ae49b8f8b0350a9d92bcc3c38b5f 12:20:27 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:20:27 INFO - mozversion platform_version: 48.0a2 12:20:27 INFO - using testdate: 1464636027 12:20:27 INFO - actual date: 1464636027 12:20:27 INFO - starting webserver on 'localhost:49271' 12:20:27 INFO - SUITE-START | Running 2 tests 12:20:27 INFO - TEST-START | dromaeo_css 12:20:27 INFO - operating with platform_type : mac_ 12:20:27 INFO - Initialising browser for dromaeo_css test... 12:20:27 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/tmpMSDSgn/profile http://localhost:49271/getInfo.html) 12:20:30 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 12:20:30 INFO - PROCESS | 1668 | colorDepth:24 12:20:30 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 12:20:30 INFO - PROCESS | 1668 | __metrics 12:20:30 ERROR - PROCESS | 1668 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2940: TypeError: this.tabs is undefined 12:20:30 INFO - TEST-INFO | 1668: exit 0 12:20:30 INFO - Browser initialized. 12:20:30 INFO - Running cycle 1/1 for dromaeo_css test... 12:20:30 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/tmpMSDSgn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:20:42 INFO - PROCESS | 1672 | RSS: Main: 221179904 12:20:42 INFO - PROCESS | 1672 | 12:21:32 INFO - PROCESS | 1672 | 1464636092534 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] 12:23:43 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 12:23:44 INFO - PROCESS | 1672 | RSS: Main: 232513536 12:23:44 INFO - PROCESS | 1672 | 12:26:46 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 12:26:47 INFO - PROCESS | 1672 | RSS: Main: 243257344 12:26:47 INFO - PROCESS | 1672 | 12:29:49 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 12:29:49 INFO - PROCESS | 1672 | RSS: Main: 245088256 12:29:49 INFO - PROCESS | 1672 | 12:32:51 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 12:32:52 INFO - PROCESS | 1672 | RSS: Main: 247668736 12:32:52 INFO - PROCESS | 1672 | 12:35:54 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 12:35:55 INFO - PROCESS | 1672 | RSS: Main: 249782272 12:35:55 INFO - PROCESS | 1672 | 12:38:57 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 12:38:57 INFO - PROCESS | 1672 | RSS: Main: 251158528 12:38:57 INFO - PROCESS | 1672 | 12:38:57 INFO - PROCESS | 1672 | __start_tp_report 12:38:57 INFO - PROCESS | 1672 | _x_x_mozilla_page_load 12:38:57 INFO - PROCESS | 1672 | _x_x_mozilla_page_load_details 12:38:57 INFO - PROCESS | 1672 | |i|pagename|runs| 12:38:57 INFO - PROCESS | 1672 | |0;dojo.html;2821.4639218548486;3182.663281421803;3194.9361012779746;3415.2486452980343;3442.156671615455;5696.601237913337;5712.22884910537;5746.827595172141;5747.195392645033;5767.221425107609;5665.405132461084;5687.573431992597;5718.628289161202;5751.166080918264;5755.935151996873;4508.098380323941;4994.175960966436;5278.524932756051;5324.520793128618;5363.6245462817615;5126.282320475137;5258.605604579653;5270.762815673284;5272.551833094191;5273.551748101416;5165.535101840834;5205.739713014353;5218.243354713564;5231.11071117909;5324.520793128618;5251.868703282429;5287.735613219362;5318.680879147263;5326.946730532683;5348.251244825728;4708.623310135181;5117.360329958755;5164.14791559396;5213.582913366921;5246.081935661244;5327.573794096463;5456.236126942231;5559.388467268597;5610.214569960209;5640.238567793336;6178.814635560941;6232.277706474024;6241.594296370767;6259.937400625981;6268.341824108448;6269.561130720851;6302.558820882494;6306.085617585424;6311.368863113697;6321.873562528724;5621.100623900197;5729.541636669056;5781.739821707991;5795.478406943395;5835.124731290341;5368.355797304349;6093;6295.464885484694;6319.8104056878365;6337.809865704036;5982.132590774313;5998.070299103646;6154.446099851035;6156.753729850845;6171.290301615371;6164.321924588292;6231.2522497300615;6253.1245625612455;6289.55973081889;6301.275353334425;6192.411720886508;6218.875622487525;6237.625742455376;6237.96881015592;6243.875122497525;6036.63780173191;6151.2618485781095;6250.937490625127;6256.812295631138;6267.811965641038;4438.378626992224;4895.216765317532;4942.1104201244125;4963.10664080469;4963.478834722302;4431.556844315543;4447.421835161473;4451.354553589711;4454.420925279693;4503.324501324828;5072.644914855925;5522.116461366242;5723.141528770718;5803.65178089316;5813.94186058134;6228.47058000066;6246.156768836149;6249.0938813871735;6255.499560035186;6276.968615156895;5804.361520232771;5828.184054232411;5829.650220986754;5839.9124013139835;5863.853403664857;5333.759980800874;5358.464153584689;5407.675539467645;5445.346558413015;5508.20131080991;6331.556791024584;6409.166808314889;6417.679116044273;6430.971044632836;6435.549511534241;5122.436531981404;5173.430922598586;6175.3515880831865;6225.439710425902;6321.905171422524;6219.253689557282;6314.968425157845;6340.619562826245;6363.809085727436;6415.230172379344;6139;6160.137580738789;6161.599496032743;6191.783287584913;6243.344448832807;5797.985352563369;5919.6744179070565;6175.505959523138;6177.289611694712;6199.194104766351;5876.706164691834;6061.393860613904;6088.51291896656;6105.358937311518;6109.725062372293;5528.170774383874;6122.245686735438;6148.877022459436;6151.6001459904965;6174.475169610633;5846.649201047951;5996.430339117776;6059.666718330534;6072.574919755575;6091.969540152271;5007.424146223086;6021.42796434338;6059.787907423219;6125.264968203844;6183.319834818077;5983.970080149571;6017.976943919457;6032.668203248863;6041.546883983631;6059.454649081604 12:38:57 INFO - PROCESS | 1672 | |1;ext.html;517.4256575201528;522.0733198572535;541.2638811216744;568.579251353998;592.1857446011734;37709.24581508372;38914.416283755694;38929;38976.805115974414;39044.414333784945;40362.192756144934;40927.59072409275;40939.79530102353;40954;41070.38394424086;9368.625254989809;9611.95194024029;9616.663416780415;9624.37441566296;9653.855192172123;9353;9544;9560.904390956106;9570.234381249484;9578;9524.904750952472;9576.90423095767;9589.760255993586;9595.80808383832;9597;9506.95423503414;9576.186024187953;9595.616175353047;9612.615495380176;9616.374935629161;9577.760555986086;9636.180924621416;9664.130228279419;9681.128698417177;9688;38727.80636096802;39168.608313917066;39207;39217.41173882394;39333.60666393328;3141.2146963259183;3279.770416070876;3299.5875515560556;3301.4552598821165;3314.105191598279;28462.85768571144;28570.714292857014;28872.8556357219;28874.422511549652;29203.977860774772;2733.439644872796;2739.0960982875804;2739.356251280967;2755.256080858157;2792.5113105206506;486138;490157.99999999645;493422;495519;499185.5040724773;59183;59315.81368372695;59721.402785971586;59929;59991;59070;63084.79203227957;63410;67551.66224168848;68616;17150.742738858928;17276.913615431844;17278.308867645406;17364.739528907077;17377.957322560687;13128.803067953988;13149.474021039052;13248.735025299633;13250.403731832092;13265.867341326657;19207.423777286705;19721.94795998759;19811.603767924847;19868;19917.402477925687;6680.766173184013;8164.469014605373;8183.468074575134;8211.384146189059;8253.917460825434;745.5265906149634;953.1659797677032;980.5587485631495;1027.897210278981;1082.4587706146926;29596.852015739783;30217.848910755307;30268;30718.69281307203;30941.071767847;1091.4215465803138;1112.8386383974278;1130.4799792095728;1159.3855256713955;1217.6834023154045;15141;15346.848986326071;15500;15556.922215388851;15734.370625175094;638504;640507;651574;652108;652665;47256.527434725904;54252.72873635607;60864.478388039766;60992.085118723255;61199.69400152971;1476.269246722879;1496.1098146602972;1502.241368109098;1505.1796770759963;1508.8113985751781;29468.410631786814;29928.401431970804;29978;30412.39175216439;30556.694433055385;50403.49596503988;50925;51196.488035119175;51383.68773405131;51442.22836657528;576052;579361;579505;580199;580524;26499.205023849314;26647.46705065849;26799.732002679724;27074.1877743668;27099.86450067737;45696.77151614353;46411.53588464072;47113.293300599835;47684.52315476801;47696.28455573239;37370.626293736714;38107.23785524329;38111.23777524378;38264.42603361008;38634;37628;37835.243295134496;37894.431583525715;38096.61903380931;38630.6136938627;33462.330753385286;37488.25023499569;37950.810245949695;38114;38758.61241387663;30670.079897603107;31207.375852483277;31670.841645791625;31689.683103169595;31828.681713183498;19778.90110549438;24078.87960560186;24273.75726242715;24478.898449569457;24529.632055519538 12:38:57 INFO - PROCESS | 1672 | |2;jquery.html;396.811514530598;399.5684660566587;403.30228704341505;411.8929078439606;413.66286476521634;14901.776473352907;20296.59406811863;20626.071826767795;21034.94825258737;21128.4717882053;18958.71561926572;19095.331663391786;19295.517612059706;19939.501512462197;20261.290854820083;5387.676739395629;5557;5797.826065218051;5834.5915785895;5846.824595262149;5713.457221563945;5800.854978625547;5826.980278451275;5827.475527202551;5828.883422331551;5542.63972841766;5750.856228594298;5841.182234487154;5843.707814609274;5850.531957443415;5783.6240644358195;5819.563532735019;5825.533957283428;5829.941700582982;5841.737121829507;5053.797848086072;5090.9236361454605;5138;5145.974270128625;5162.6128040397125;25709;26018.86990565054;26411.867940660173;26513.86743066272;26566.6015009775;2138.700581918533;2584.961225581599;2607.413332000304;2666.200139958024;2911.505044142501;13814.970104448319;14654.487092951802;14819.184944828137;14991.475298364505;15144.318505667272;1663.1850393307193;1669.0986867091876;1688.5356526955063;1702.7190513565247;1710.136381127535;113952.86047139505;117515;117773;119159.40420297929;119546.40226798897;10667.73330666724;11001.669949901516;11163.046466868427;11257.493412796444;11359.829602555968;14157.362918668685;14181.645458863612;14237.85762142386;14266.928665356607;14268.357923893449;8700.477971321741;8832.911670883337;8842.027376988526;8901.465912045298;8915.37592368528;8886.644534218689;8959.41763785352;8961.955190224007;9077.500737459502;9083.227925626263;6644.8671026579195;6701.731930722716;6775.830604234933;6782.355676210752;6816.727330906709;1774.7870255569414;1883.3878989328523;1912.7991560886185;1914.0429785107447;1934.951626209356;904.0552622509493;958.678842587741;1013.7871047080196;1018.2566726289701;1035.5754140802235;20814;20867.582648346946;20931.47671308199;21096.261630843153;21155.25956591512;823.9011318641801;959.4195511715451;988.1008282462924;1003.1573478278135;1068.4978060311641;9223.815523689489;11375.829362559569;11379.544818207345;11379.658610241706;11419.429028548539;612697;623615;627111;643906;644767;115750.84249157569;116441;117059.41470292765;119691;119894;1016.3393794033938;1038.444432228754;1060.9151267898549;1061.0291583201285;1064.6486659402378;18165.455036348932;18583.81416185821;18633.9068304663;18757.718634220226;18983.71524427162;37944;40893.795531023345;51468.48531514637;52127.73936130295;52380.52434456929;73368.63315683388;74569.88145177935;74695.62652186921;76430.6178469104;79783;8056.83886322282;8096.2308580687095;8211.888854012659;8287.792805179677;8323.334133269445;6938.93061069403;9962.452065136487;12540.372981351078;12615.432305546456;13071.607851764462;10990.285631433931;11077.944610276896;11210.88789112131;11342.886571134182;11522.366269855238;10771.730706732395;10902.528158698475;11016.504257308601;11053.502592383202;11196.608118715805;11304.60433884843;11336.206465547333;11558.826617600575;11578.478968446609;11774.116941229271;8071.273585377344;8157;8166.754997350089;8186.918130818854;8336.154872632454;11185.161112916727;11236.325820450798;11303.604373846876;11484.88515114838;11510.079193664655 12:38:57 INFO - PROCESS | 1672 | |3;mootools.html;1567.0910871694418;1584.516722399668;1813.3562585282225;1885.1893685715154;1987.195185949691;7979.361651067915;8583.184597463247;8711.869321960168;8916.554172291375;8933.330000249984;8647.740567782961;8677.47935123893;8768.342374321935;8771.561421928895;8821.647134114628;2008.7689915659666;2376.738558758535;2464.6549483072295;2473.6536884836137;2495.6131799571094;2454.1778504201116;2475.121331927167;2475.715292741331;2496.151308555091;2500.6374075759004;2459.102427613926;2471.7033955925313;2475;2497.7502249774966;2503.5368456835545;2026.5035066408677;2449.8040156787415;2461.79074778644;2464.581021226396;2476.950460990779;2451.117597664839;2466.728659847415;2469.123461171276;2470.419451428913;2484.714257860351;8819.735407937773;8866.246369058637;8930.151635594673;8953.283737301;8954.37319387643;5393.946060539383;5470.863228419282;5501.14732216507;5510.614257002011;5567.025770490189;2955.7044295570486;3538.0800991742076;3544.663256990582;3579.8210089495683;3633.3823250047553;906.5014242166782;1056.3292309383519;1072.0565902006263;1074.425182527352;1087.6682611803396;11410.885891141066;11629.418529073513;11677.708057298636;11681.007114395286;11686;3604.4593311003036;4702.929456058162;4717.268823332389;4727.125481785881;4781.784819683053;9322.25421966241;9324.487153206639;9326.673566425143;9327;9356;5359.598030147755;5368.32895888014;5370.194470829329;5385.084535628953;5437.945620543823;4431.911361772746;4897.804087836446;4988.27669987857;4989.750512474435;4992.226204938241;3864.381698928158;3899.1811719538646;3930.6069393060466;3952.4664170336637;4031.4355990161403;1044.6239353832614;1230.390956476532;1237.294741997053;1242.3415589737454;1254.2286493806425;846.7713717296295;940.1209868772719;960.582146766159;964.6961207219704;969.9321047526605;5719.512926639104;5747.224124743189;5766.389842689471;5844.1233814927255;5896.115582662635;457.82602611007604;471.44134200971956;477.2364217252368;478.3255609590461;480.0878331170803;3927.842886284574;3934.7245692801803;3976.900577485586;4005.819738111792;4126.711130220856;10532.684019479428;10823.350598964087;11017.83473247902;11022;11038.724031899266;10826.67519974402;10878.945605271923;10884.945575272073;10919.543102801807;10919.836202456969;8344.541050242173;8370.958145209357;8402.537860417735;8427.199416055464;8514.78713032179;5425.13197888336;5427.3215848019;5492.313460817398;5524.309461317335;5616.550675945996;9479.194268487256;9682.806343872942;9688.370255933341;9724.319297649097;9860.211183105192;8214.876776848472;9458.24334053288;9650.806983860424;9764.951175244078;9962.900370996198;6006.639601623917;6893.103896493424;7597.848043039219;7638.885416718865;7717.151113377637;8376.32989360862;8383.706570270011;8388.454750441202;8416.621252043795;8455.154484551742;7312.96343518279;7388.408927285912;7487.47587668858;7618.123915749759;7635.42734294919;7254.854902902018;7324.267573242633;7327.816804579928;7397.445191610758;7438.5536867788105;6941.375276225164;6978.337057979484;6996.895046574204;7043.964780176067;7052.118485189351;4852.975735121302;4924;4932.087563800708;4983.426905905867;5021.924671130009;4993.80024799004;5070.873228169326;5074.720890351066;5087.287779710918;5158.767855446439 12:38:57 INFO - PROCESS | 1672 | |4;prototype.html;256.4025819839775;275.5370976759045;308.9274020605159;314.4198952931841;324.78239579481743;5409.702466364353;6685.097511835901;6713.093732346132;6744.898826517606;6781.593104413739;5318.787248510064;6632.038354438616;6639;6643.302453242412;6644;4783.9760801196035;4858.101251268509;4955.430125535573;4977.129002424579;4984.651074424792;4916.041371932474;4960.727160006199;4972.353594032788;4978.406909788869;4991.151504244287;4933.679310844802;4936.876578085541;4941.728204948744;4944.33251511045;4972.502749725017;4912.7297998610065;4930.5069493050405;4942.53045960635;4954.826581069663;4999.825006124768;3869.7678139311734;3892.1631849152286;4281.764502952336;4282.122164956177;4284.957150428487;6278.717457714368;6411.326810684857;6462.450691691212;6482.189726284215;6488.6106833589665;5856.590038697293;5909.645421274738;5916.4675179233645;5916.674582897939;5941.613795103348;7177.46169037319;7194;7207.89188162178;7222.061132052852;7242.818929526751;4794;4794.065157294329;4797.376341075673;4797.736124513151;4804;7337.559746415232;7597.126330472011;7628.351590114847;7630.122535908331;7633.2366763323225;4098.344264917599;4757.024809913959;4844.8304309349005;4848.878778030577;4864.683795553277;4908.116539022939;4945.4312754033745;4958.851234462972;4960.553550180501;4966.627502937295;4850.95149048512;5753.90675771602;5839.854003649943;5851.502622277069;5856.502197313191;7222.74720384784;7232.276772322725;7237.167725711505;7238.855222895512;7250.166230883516;1705.4627792245403;1730.7057800174;1731.3680506614994;1733.9219735111951;1735.1497766094776;853.9794945040617;1029.8558201851747;1042.6507120114695;1043.1498328861953;1106.3527836215742;6663.566868153554;6701.966490167615;6708.725342184969;6714.664266786641;6726.596404215763;7285.526440781332;7445.404367650575;7460.440466964999;7475.252474752481;7477.401807855358;6637.933620663828;6799.830004249834;6800.183977922582;6801.863962720718;6812.352826481576;3947.1908258806875;3993.780342081155;4003.159336539301;4013.819378127991;4017.397390391465;7872.842543149105;7888.171741967126;7898.052233731989;7912.49662564111;7926.722564710323;6920.9653951729915;7209.495335326476;7303.89044164338;7740.574268415291;7791.4156438267355;7844.862494938317;7881.330086942674;7921.722739704082;7922.564258965812;7924.48490848093;7474.2525747424825;7492.925070749441;7525.36034437079;7528.548287102792;7556.8866467001935;6293.590916590407;6332.746690132343;7363.889541656772;7377.5204611700065;7393.963030184815;6344.68276586178;7619.69521219145;7862.174471680391;7865.292123708665;7910.2089791020435;3842.481265029185;4661.20759470884;4682.531746825426;4687.367205427224;4692.577668009895;5280.762365693477;7387;7451.552906825608;7476.962615186889;7479.214682458263;5905.616134951214;7111.7866464006165;7119.5372300802355;7236.38490728294;7253.165885923187;5943.554233432596;7177.892331614926;7185.676644551112;7189.065421495172;7235.891461628185;6015.608985415934;6703.530752847449;7039.296070392919;7050.435965122675;7072;7177.712891484282;7218.963905180441;7227.241139680258;7229.168645605822;7264.527805692613;6996;7046.295370462913;7051.929480705127;7058.61177635235;7072.71709131629 12:38:57 INFO - PROCESS | 1672 | |5;yui.html;650.9739610415583;669.4242951062083;690.3821080133281;729.6570611812447;759.2217976574011;14666.12003279804;14855.331510082045;15055.999999999985;15114.697706045872;15121.697566048673;13514.662133446669;13571.660708482294;13588.184708917472;13610.775030247276;13611.047226694136;744.6314074533086;910.7222297199352;923.6028507741669;944.0182210501071;951.4291425144893;927.0729270729271;939.6100618243421;946.1059298962483;948.6205517792869;950.3632566180676;986.1174249047099;992.8758905136858;992.885818130917;996.0587245053428;996.5814357969634;955.5126885288483;961.8509131084693;986.8174387738255;991.9801603967952;994.3139233928603;689.7103216648969;708.6810935079235;730.5288089182501;733.9559626422432;734.673070483635;13853.861461385459;13960.511382101578;14124.152550846879;14243;14250.358733857001;12539.686507837287;12626.936865315707;12637.115401921868;12693.619191424179;12789.680257993532;9800.215982721365;9973.952734962797;10051.59793608262;10067.798644027152;10100.292979491438;3517.6482351764876;3522.84147213376;3554.4668299754985;3578.5169002184634;3732.850685972558;80295;82066.58966705189;83677;83756.58121709352;83810.58094709487;7900.9604951976025;9333.300002499704;9439;9445.76385590352;9785.608575657036;21587.460313492287;21593.24423645165;21597.784022159576;21696.24063157782;21777.34667959964;7359.337659610661;7443.330100291;7475.177730449646;7539.359154471822;7570.432217583703;3955.3473676843573;3959.5050618672667;4064.5935406459707;4076.184763047402;4096.221717873654;3365.6297807241185;3405.233822389992;3437.9656203438144;3445.2937147885123;3449.413599688059;608.0605464557182;610.5268416976878;611.8868009418272;617.9134921111048;620.3455354600849;554.4898693202183;573.6844735395516;597.3697748874891;603.1947350287415;607.5504126946028;5772.163036359704;6810.591364518045;7015.89476157858;8000.15998320168;8143.959280203561;553.7093026161217;574.454268444979;574.6695650001266;596.5049009322252;607.7447472061745;3152.4956007038763;3742.8315725792404;3868.3810590305416;3876.903077423087;3927.489426374553;81473.96315092052;82123;92487;93057;93884.12231755327;56751;60454;76409.23590764133;76825;78982.21017789864;63736;68529.97205041928;68623;69189.3081069193;69908;8408.95795521043;8482.787930301793;8550.230479256898;8617.95691021541;8788.736337909873;45339.546604533534;48368.7581562104;48993.02013959772;49783.751081244365;49848.50151498439;62449.68775156095;64055.6797216011;65283.67358163179;65756;66607.00089498758;10762.677119686421;10825;11104.944475277573;11108.888911110786;11167.274127181707;11804.881951180378;11990.400479975791;12053.397330133634;12061.939690301493;12430.254184748945;10470.534125222628;11129.054030407506;11175.664730058112;11405.828912566483;12029.879701202875;10348;11181.888181118085;11300;11547.595834145764;11669.474873630877;10762.946185269024;10858;11106.555737770399;11186.159970468245;11306.94346528262;9543.733811516073;9719.416834989923;9768.316217864682;9861.852072219066;9879.50602469888;9582.377145485521;9684.983076777118;9776.804463910825;9822.165115965223;9886.406815591088 12:38:57 INFO - PROCESS | 1672 | __end_tp_report 12:38:57 INFO - PROCESS | 1672 | __start_cc_report 12:38:57 INFO - PROCESS | 1672 | _x_x_mozilla_cycle_collect,416 12:38:57 INFO - PROCESS | 1672 | __end_cc_report 12:38:57 INFO - PROCESS | 1672 | __startTimestamp1464637137838__endTimestamp 12:38:57 INFO - PROCESS | 1672 | 12:38:57 INFO - PROCESS | 1672 | ------- Summary: start ------- 12:38:57 INFO - PROCESS | 1672 | Number of tests: 6 12:38:57 INFO - PROCESS | 1672 | 12:38:57 INFO - PROCESS | 1672 | [#0] dojo.html Cycles:165 Average:5709.67 Median:5876.71 stddev:672.66 (11.4%) stddev-sans-first:635.43 12:38:57 INFO - PROCESS | 1672 | Values: 2821.5 3182.7 3194.9 3415.2 3442.2 5696.6 5712.2 5746.8 5747.2 5767.2 5665.4 5687.6 5718.6 5751.2 5755.9 4508.1 4994.2 5278.5 5324.5 5363.6 5126.3 5258.6 5270.8 5272.6 5273.6 5165.5 5205.7 5218.2 5231.1 5324.5 5251.9 5287.7 5318.7 5326.9 5348.3 4708.6 5117.4 5164.1 5213.6 5246.1 5327.6 5456.2 5559.4 5610.2 5640.2 6178.8 6232.3 6241.6 6259.9 6268.3 6269.6 6302.6 6306.1 6311.4 6321.9 5621.1 5729.5 5781.7 5795.5 5835.1 5368.4 6093.0 6295.5 6319.8 6337.8 5982.1 5998.1 6154.4 6156.8 6171.3 6164.3 6231.3 6253.1 6289.6 6301.3 6192.4 6218.9 6237.6 6238.0 6243.9 6036.6 6151.3 6250.9 6256.8 6267.8 4438.4 4895.2 4942.1 4963.1 4963.5 4431.6 4447.4 4451.4 4454.4 4503.3 5072.6 5522.1 5723.1 5803.7 5813.9 6228.5 6246.2 6249.1 6255.5 6277.0 5804.4 5828.2 5829.7 5839.9 5863.9 5333.8 5358.5 5407.7 5445.3 5508.2 6331.6 6409.2 6417.7 6431.0 6435.5 5122.4 5173.4 6175.4 6225.4 6321.9 6219.3 6315.0 6340.6 6363.8 6415.2 6139.0 6160.1 6161.6 6191.8 6243.3 5798.0 5919.7 6175.5 6177.3 6199.2 5876.7 6061.4 6088.5 6105.4 6109.7 5528.2 6122.2 6148.9 6151.6 6174.5 5846.6 5996.4 6059.7 6072.6 6092.0 5007.4 6021.4 6059.8 6125.3 6183.3 5984.0 6018.0 6032.7 6041.5 6059.5 12:38:57 INFO - PROCESS | 1672 | 12:38:57 INFO - PROCESS | 1672 | [#1] ext.html Cycles:180 Average:70345.44 Median:38952.90 stddev:154112.08 (395.6%) stddev-sans-first:154455.23 12:38:57 INFO - PROCESS | 1672 | Values: 517.4 522.1 541.3 568.6 592.2 37709.2 38914.4 38929.0 38976.8 39044.4 40362.2 40927.6 40939.8 40954.0 41070.4 9368.6 9612.0 9616.7 9624.4 9653.9 9353.0 9544.0 9560.9 9570.2 9578.0 9524.9 9576.9 9589.8 9595.8 9597.0 9507.0 9576.2 9595.6 9612.6 9616.4 9577.8 9636.2 9664.1 9681.1 9688.0 38727.8 39168.6 39207.0 39217.4 39333.6 3141.2 3279.8 3299.6 3301.5 3314.1 28462.9 28570.7 28872.9 28874.4 29204.0 2733.4 2739.1 2739.4 2755.3 2792.5 486138.0 490158.0 493422.0 495519.0 499185.5 59183.0 59315.8 59721.4 59929.0 59991.0 59070.0 63084.8 63410.0 67551.7 68616.0 17150.7 17276.9 17278.3 17364.7 17378.0 13128.8 13149.5 13248.7 13250.4 13265.9 19207.4 19721.9 19811.6 19868.0 19917.4 6680.8 8164.5 8183.5 8211.4 8253.9 745.5 953.2 980.6 1027.9 1082.5 29596.9 30217.8 30268.0 30718.7 30941.1 1091.4 1112.8 1130.5 1159.4 1217.7 15141.0 15346.8 15500.0 15556.9 15734.4 638504.0 640507.0 651574.0 652108.0 652665.0 47256.5 54252.7 60864.5 60992.1 61199.7 1476.3 1496.1 1502.2 1505.2 1508.8 29468.4 29928.4 29978.0 30412.4 30556.7 50403.5 50925.0 51196.5 51383.7 51442.2 576052.0 579361.0 579505.0 580199.0 580524.0 26499.2 26647.5 26799.7 27074.2 27099.9 45696.8 46411.5 47113.3 47684.5 47696.3 37370.6 38107.2 38111.2 38264.4 38634.0 37628.0 37835.2 37894.4 38096.6 38630.6 33462.3 37488.3 37950.8 38114.0 38758.6 30670.1 31207.4 31670.8 31689.7 31828.7 19778.9 24078.9 24273.8 24478.9 24529.6 12:38:57 INFO - PROCESS | 1672 | 12:38:57 INFO - PROCESS | 1672 | [#2] jquery.html Cycles:180 Average:35533.67 Median:21141.87 stddev:104624.39 (494.9%) stddev-sans-first:104884.63 12:38:57 INFO - PROCESS | 1672 | Values: 396.8 399.6 403.3 411.9 413.7 14901.8 20296.6 20626.1 21034.9 21128.5 18958.7 19095.3 19295.5 19939.5 20261.3 5387.7 5557.0 5797.8 5834.6 5846.8 5713.5 5800.9 5827.0 5827.5 5828.9 5542.6 5750.9 5841.2 5843.7 5850.5 5783.6 5819.6 5825.5 5829.9 5841.7 5053.8 5090.9 5138.0 5146.0 5162.6 25709.0 26018.9 26411.9 26513.9 26566.6 2138.7 2585.0 2607.4 2666.2 2911.5 13815.0 14654.5 14819.2 14991.5 15144.3 1663.2 1669.1 1688.5 1702.7 1710.1 113952.9 117515.0 117773.0 119159.4 119546.4 10667.7 11001.7 11163.0 11257.5 11359.8 14157.4 14181.6 14237.9 14266.9 14268.4 8700.5 8832.9 8842.0 8901.5 8915.4 8886.6 8959.4 8962.0 9077.5 9083.2 6644.9 6701.7 6775.8 6782.4 6816.7 1774.8 1883.4 1912.8 1914.0 1935.0 904.1 958.7 1013.8 1018.3 1035.6 20814.0 20867.6 20931.5 21096.3 21155.3 823.9 959.4 988.1 1003.2 1068.5 9223.8 11375.8 11379.5 11379.7 11419.4 612697.0 623615.0 627111.0 643906.0 644767.0 115750.8 116441.0 117059.4 119691.0 119894.0 1016.3 1038.4 1060.9 1061.0 1064.6 18165.5 18583.8 18633.9 18757.7 18983.7 37944.0 40893.8 51468.5 52127.7 52380.5 73368.6 74569.9 74695.6 76430.6 79783.0 8056.8 8096.2 8211.9 8287.8 8323.3 6938.9 9962.5 12540.4 12615.4 13071.6 10990.3 11077.9 11210.9 11342.9 11522.4 10771.7 10902.5 11016.5 11053.5 11196.6 11304.6 11336.2 11558.8 11578.5 11774.1 8071.3 8157.0 8166.8 8186.9 8336.2 11185.2 11236.3 11303.6 11484.9 11510.1 12:38:57 INFO - PROCESS | 1672 | 12:38:57 INFO - PROCESS | 1672 | [#3] mootools.html Cycles:180 Average:5678.66 Median:5072.80 stddev:3174.45 (62.6%) stddev-sans-first:3168.32 12:38:57 INFO - PROCESS | 1672 | Values: 1567.1 1584.5 1813.4 1885.2 1987.2 7979.4 8583.2 8711.9 8916.6 8933.3 8647.7 8677.5 8768.3 8771.6 8821.6 2008.8 2376.7 2464.7 2473.7 2495.6 2454.2 2475.1 2475.7 2496.2 2500.6 2459.1 2471.7 2475.0 2497.8 2503.5 2026.5 2449.8 2461.8 2464.6 2477.0 2451.1 2466.7 2469.1 2470.4 2484.7 8819.7 8866.2 8930.2 8953.3 8954.4 5393.9 5470.9 5501.1 5510.6 5567.0 2955.7 3538.1 3544.7 3579.8 3633.4 906.5 1056.3 1072.1 1074.4 1087.7 11410.9 11629.4 11677.7 11681.0 11686.0 3604.5 4702.9 4717.3 4727.1 4781.8 9322.3 9324.5 9326.7 9327.0 9356.0 5359.6 5368.3 5370.2 5385.1 5437.9 4431.9 4897.8 4988.3 4989.8 4992.2 3864.4 3899.2 3930.6 3952.5 4031.4 1044.6 1230.4 1237.3 1242.3 1254.2 846.8 940.1 960.6 964.7 969.9 5719.5 5747.2 5766.4 5844.1 5896.1 457.8 471.4 477.2 478.3 480.1 3927.8 3934.7 3976.9 4005.8 4126.7 10532.7 10823.4 11017.8 11022.0 11038.7 10826.7 10878.9 10884.9 10919.5 10919.8 8344.5 8371.0 8402.5 8427.2 8514.8 5425.1 5427.3 5492.3 5524.3 5616.6 9479.2 9682.8 9688.4 9724.3 9860.2 8214.9 9458.2 9650.8 9765.0 9962.9 6006.6 6893.1 7597.8 7638.9 7717.2 8376.3 8383.7 8388.5 8416.6 8455.2 7313.0 7388.4 7487.5 7618.1 7635.4 7254.9 7324.3 7327.8 7397.4 7438.6 6941.4 6978.3 6996.9 7044.0 7052.1 4853.0 4924.0 4932.1 4983.4 5021.9 4993.8 5070.9 5074.7 5087.3 5158.8 12:38:57 INFO - PROCESS | 1672 | 12:38:57 INFO - PROCESS | 1672 | [#4] prototype.html Cycles:180 Average:5852.69 Median:6641.15 stddev:1879.39 (28.3%) stddev-sans-first:1837.12 12:38:57 INFO - PROCESS | 1672 | Values: 256.4 275.5 308.9 314.4 324.8 5409.7 6685.1 6713.1 6744.9 6781.6 5318.8 6632.0 6639.0 6643.3 6644.0 4784.0 4858.1 4955.4 4977.1 4984.7 4916.0 4960.7 4972.4 4978.4 4991.2 4933.7 4936.9 4941.7 4944.3 4972.5 4912.7 4930.5 4942.5 4954.8 4999.8 3869.8 3892.2 4281.8 4282.1 4285.0 6278.7 6411.3 6462.5 6482.2 6488.6 5856.6 5909.6 5916.5 5916.7 5941.6 7177.5 7194.0 7207.9 7222.1 7242.8 4794.0 4794.1 4797.4 4797.7 4804.0 7337.6 7597.1 7628.4 7630.1 7633.2 4098.3 4757.0 4844.8 4848.9 4864.7 4908.1 4945.4 4958.9 4960.6 4966.6 4851.0 5753.9 5839.9 5851.5 5856.5 7222.7 7232.3 7237.2 7238.9 7250.2 1705.5 1730.7 1731.4 1733.9 1735.1 854.0 1029.9 1042.7 1043.1 1106.4 6663.6 6702.0 6708.7 6714.7 6726.6 7285.5 7445.4 7460.4 7475.3 7477.4 6637.9 6799.8 6800.2 6801.9 6812.4 3947.2 3993.8 4003.2 4013.8 4017.4 7872.8 7888.2 7898.1 7912.5 7926.7 6921.0 7209.5 7303.9 7740.6 7791.4 7844.9 7881.3 7921.7 7922.6 7924.5 7474.3 7492.9 7525.4 7528.5 7556.9 6293.6 6332.7 7363.9 7377.5 7394.0 6344.7 7619.7 7862.2 7865.3 7910.2 3842.5 4661.2 4682.5 4687.4 4692.6 5280.8 7387.0 7451.6 7477.0 7479.2 5905.6 7111.8 7119.5 7236.4 7253.2 5943.6 7177.9 7185.7 7189.1 7235.9 6015.6 6703.5 7039.3 7050.4 7072.0 7177.7 7219.0 7227.2 7229.2 7264.5 6996.0 7046.3 7051.9 7058.6 7072.7 12:38:57 INFO - PROCESS | 1672 | 12:38:57 INFO - PROCESS | 1672 | [#5] yui.html Cycles:180 Average:17767.65 Median:607.90 stddev:24795.96 (4078.9%) stddev-sans-first:24832.21 12:38:57 INFO - PROCESS | 1672 | Values: 651.0 669.4 690.4 729.7 759.2 14666.1 14855.3 15056.0 15114.7 15121.7 13514.7 13571.7 13588.2 13610.8 13611.0 744.6 910.7 923.6 944.0 951.4 927.1 939.6 946.1 948.6 950.4 986.1 992.9 992.9 996.1 996.6 955.5 961.9 986.8 992.0 994.3 689.7 708.7 730.5 734.0 734.7 13853.9 13960.5 14124.2 14243.0 14250.4 12539.7 12626.9 12637.1 12693.6 12789.7 9800.2 9974.0 10051.6 10067.8 10100.3 3517.6 3522.8 3554.5 3578.5 3732.9 80295.0 82066.6 83677.0 83756.6 83810.6 7901.0 9333.3 9439.0 9445.8 9785.6 21587.5 21593.2 21597.8 21696.2 21777.3 7359.3 7443.3 7475.2 7539.4 7570.4 3955.3 3959.5 4064.6 4076.2 4096.2 3365.6 3405.2 3438.0 3445.3 3449.4 608.1 610.5 611.9 617.9 620.3 554.5 573.7 597.4 603.2 607.6 5772.2 6810.6 7015.9 8000.2 8144.0 553.7 574.5 574.7 596.5 607.7 3152.5 3742.8 3868.4 3876.9 3927.5 81474.0 82123.0 92487.0 93057.0 93884.1 56751.0 60454.0 76409.2 76825.0 78982.2 63736.0 68530.0 68623.0 69189.3 69908.0 8409.0 8482.8 8550.2 8618.0 8788.7 45339.5 48368.8 48993.0 49783.8 49848.5 62449.7 64055.7 65283.7 65756.0 66607.0 10762.7 10825.0 11104.9 11108.9 11167.3 11804.9 11990.4 12053.4 12061.9 12430.3 10470.5 11129.1 11175.7 11405.8 12029.9 10348.0 11181.9 11300.0 11547.6 11669.5 10762.9 10858.0 11106.6 11186.2 11306.9 9543.7 9719.4 9768.3 9861.9 9879.5 9582.4 9685.0 9776.8 9822.2 9886.4 12:38:57 INFO - PROCESS | 1672 | -------- Summary: end -------- 12:38:57 INFO - PROCESS | 1672 | 12:38:58 INFO - TEST-INFO | 1672: exit 0 12:38:58 INFO - TEST-OK | dromaeo_css | took 1110923ms 12:38:58 INFO - TEST-START | kraken 12:38:58 INFO - operating with platform_type : mac_ 12:38:58 INFO - Initialising browser for kraken test... 12:38:58 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/tmpAmsSnV/profile http://localhost:49271/getInfo.html) 12:39:01 INFO - PROCESS | 1680 | __metrics Screen width/height:1600/1200 12:39:01 INFO - PROCESS | 1680 | colorDepth:24 12:39:01 INFO - PROCESS | 1680 | Browser inner width/height: 1024/689 12:39:01 INFO - PROCESS | 1680 | __metrics 12:39:01 ERROR - PROCESS | 1680 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2940: TypeError: this.tabs is undefined 12:39:01 INFO - TEST-INFO | 1680: exit 0 12:39:01 INFO - Browser initialized. 12:39:01 INFO - Running cycle 1/1 for kraken test... 12:39:01 INFO - TEST-INFO | started process 1683 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/tmpAmsSnV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:39:13 INFO - PROCESS | 1683 | RSS: Main: 221179904 12:39:13 INFO - PROCESS | 1683 | 12:39:16 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 12:39:17 INFO - PROCESS | 1683 | RSS: Main: 232960000 12:39:17 INFO - PROCESS | 1683 | 12:39:19 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 12:39:20 INFO - PROCESS | 1683 | RSS: Main: 241889280 12:39:20 INFO - PROCESS | 1683 | 12:39:23 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 12:39:24 INFO - PROCESS | 1683 | RSS: Main: 245186560 12:39:24 INFO - PROCESS | 1683 | 12:39:26 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 12:39:26 INFO - PROCESS | 1683 | RSS: Main: 246484992 12:39:26 INFO - PROCESS | 1683 | 12:39:29 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:39:29 INFO - PROCESS | 1683 | RSS: Main: 246689792 12:39:29 INFO - PROCESS | 1683 | 12:39:32 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 12:39:33 INFO - PROCESS | 1683 | RSS: Main: 246513664 12:39:33 INFO - PROCESS | 1683 | 12:39:36 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 12:39:36 INFO - PROCESS | 1683 | RSS: Main: 246091776 12:39:36 INFO - PROCESS | 1683 | 12:39:39 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 12:39:40 INFO - PROCESS | 1683 | RSS: Main: 246403072 12:39:40 INFO - PROCESS | 1683 | 12:39:41 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:39:42 INFO - PROCESS | 1683 | RSS: Main: 247877632 12:39:42 INFO - PROCESS | 1683 | 12:39:44 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:39:44 INFO - PROCESS | 1683 | RSS: Main: 247296000 12:39:44 INFO - PROCESS | 1683 | 12:39:47 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:39:47 INFO - PROCESS | 1683 | RSS: Main: 247513088 12:39:47 INFO - PROCESS | 1683 | 12:39:50 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:39:51 INFO - PROCESS | 1683 | RSS: Main: 247595008 12:39:51 INFO - PROCESS | 1683 | 12:39:54 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:39:54 INFO - PROCESS | 1683 | RSS: Main: 247545856 12:39:54 INFO - PROCESS | 1683 | 12:39:56 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 12:39:56 INFO - PROCESS | 1683 | RSS: Main: 249425920 12:39:56 INFO - PROCESS | 1683 | 12:39:56 INFO - PROCESS | 1683 | __start_tp_report 12:39:56 INFO - PROCESS | 1683 | _x_x_mozilla_page_load 12:39:56 INFO - PROCESS | 1683 | _x_x_mozilla_page_load_details 12:39:56 INFO - PROCESS | 1683 | |i|pagename|runs| 12:39:56 INFO - PROCESS | 1683 | |0;ai-astar;113;119;107;116;117;118;108;116;115;118 12:39:56 INFO - PROCESS | 1683 | |1;audio-beat-detection;151;124;157;152;120;140;122;171;191;153 12:39:56 INFO - PROCESS | 1683 | |2;audio-dft;195;194;197;197;195;194;195;195;197;194 12:39:56 INFO - PROCESS | 1683 | |3;audio-fft;66;74;74;141;58;57;58;63;142;83 12:39:56 INFO - PROCESS | 1683 | |4;audio-oscillator;76;82;129;80;79;80;115;79;80;118 12:39:56 INFO - PROCESS | 1683 | |5;imaging-gaussian-blur;88;94;127;86;90;86;90;87;88;85 12:39:56 INFO - PROCESS | 1683 | |6;imaging-darkroom;94;95;95;95;96;149;95;127;148;100 12:39:56 INFO - PROCESS | 1683 | |7;imaging-desaturate;99;97;90;92;98;91;91;94;91;91 12:39:56 INFO - PROCESS | 1683 | |8;json-parse-financial;61;65;66;67;65;66;68;81;66;61 12:39:56 INFO - PROCESS | 1683 | |9;json-stringify-tinderbox;64;104;107;89;76;73;68;67;66;78 12:39:56 INFO - PROCESS | 1683 | |10;stanford-crypto-aes;81;89;81;101;72;91;99;103;75;91 12:39:56 INFO - PROCESS | 1683 | |11;stanford-crypto-ccm;146;150;145;144;154;143;118;158;157;170 12:39:56 INFO - PROCESS | 1683 | |12;stanford-crypto-pbkdf2;168;206;166;167;181;168;167;167;166;172 12:39:56 INFO - PROCESS | 1683 | |13;stanford-crypto-sha256-iterative;95;81;70;66;68;64;63;68;66;69 12:39:56 INFO - PROCESS | 1683 | __end_tp_report 12:39:56 INFO - PROCESS | 1683 | __start_cc_report 12:39:56 INFO - PROCESS | 1683 | _x_x_mozilla_cycle_collect,1090 12:39:56 INFO - PROCESS | 1683 | __end_cc_report 12:39:56 INFO - PROCESS | 1683 | __startTimestamp1464637196858__endTimestamp 12:39:56 INFO - PROCESS | 1683 | 12:39:56 INFO - PROCESS | 1683 | ------- Summary: start ------- 12:39:56 INFO - PROCESS | 1683 | Number of tests: 14 12:39:56 INFO - PROCESS | 1683 | 12:39:56 INFO - PROCESS | 1683 | [#0] ai-astar Cycles:10 Average:114.70 Median:116.50 stddev:4.16 (3.6%) stddev-sans-first:4.37 12:39:56 INFO - PROCESS | 1683 | Values: 113.0 119.0 107.0 116.0 117.0 118.0 108.0 116.0 115.0 118.0 12:39:56 INFO - PROCESS | 1683 | 12:39:56 INFO - PROCESS | 1683 | [#1] audio-beat-detection Cycles:10 Average:148.10 Median:152.50 stddev:22.63 (14.8%) stddev-sans-first:23.98 12:39:56 INFO - PROCESS | 1683 | Values: 151.0 124.0 157.0 152.0 120.0 140.0 122.0 171.0 191.0 153.0 12:39:56 INFO - PROCESS | 1683 | 12:39:56 INFO - PROCESS | 1683 | [#2] audio-dft Cycles:10 Average:195.30 Median:195.00 stddev:1.25 (0.6%) stddev-sans-first:1.32 12:39:56 INFO - PROCESS | 1683 | Values: 195.0 194.0 197.0 197.0 195.0 194.0 195.0 195.0 197.0 194.0 12:39:56 INFO - PROCESS | 1683 | 12:39:56 INFO - PROCESS | 1683 | [#3] audio-fft Cycles:10 Average:81.60 Median:64.50 stddev:32.66 (50.6%) stddev-sans-first:34.15 12:39:56 INFO - PROCESS | 1683 | Values: 66.0 74.0 74.0 141.0 58.0 57.0 58.0 63.0 142.0 83.0 12:39:56 INFO - PROCESS | 1683 | 12:39:56 INFO - PROCESS | 1683 | [#4] audio-oscillator Cycles:10 Average:91.80 Median:79.50 stddev:20.27 (25.5%) stddev-sans-first:20.68 12:39:56 INFO - PROCESS | 1683 | Values: 76.0 82.0 129.0 80.0 79.0 80.0 115.0 79.0 80.0 118.0 12:39:56 INFO - PROCESS | 1683 | 12:39:56 INFO - PROCESS | 1683 | [#5] imaging-gaussian-blur Cycles:10 Average:92.10 Median:88.00 stddev:12.54 (14.2%) stddev-sans-first:13.21 12:39:56 INFO - PROCESS | 1683 | Values: 88.0 94.0 127.0 86.0 90.0 86.0 90.0 87.0 88.0 85.0 12:39:56 INFO - PROCESS | 1683 | 12:39:56 INFO - PROCESS | 1683 | [#6] imaging-darkroom Cycles:10 Average:109.40 Median:95.00 stddev:22.86 (24.1%) stddev-sans-first:23.56 12:39:56 INFO - PROCESS | 1683 | Values: 94.0 95.0 95.0 95.0 96.0 149.0 95.0 127.0 148.0 100.0 12:39:56 INFO - PROCESS | 1683 | 12:39:56 INFO - PROCESS | 1683 | [#7] imaging-desaturate Cycles:10 Average:93.40 Median:93.00 stddev:3.37 (3.6%) stddev-sans-first:2.91 12:39:56 INFO - PROCESS | 1683 | Values: 99.0 97.0 90.0 92.0 98.0 91.0 91.0 94.0 91.0 91.0 12:39:56 INFO - PROCESS | 1683 | 12:39:56 INFO - PROCESS | 1683 | [#8] json-parse-financial Cycles:10 Average:66.60 Median:66.00 stddev:5.56 (8.4%) stddev-sans-first:5.52 12:39:56 INFO - PROCESS | 1683 | Values: 61.0 65.0 66.0 67.0 65.0 66.0 68.0 81.0 66.0 61.0 12:39:56 INFO - PROCESS | 1683 | 12:39:56 INFO - PROCESS | 1683 | [#9] json-stringify-tinderbox Cycles:10 Average:79.20 Median:70.50 stddev:15.68 (22.2%) stddev-sans-first:15.64 12:39:56 INFO - PROCESS | 1683 | Values: 64.0 104.0 107.0 89.0 76.0 73.0 68.0 67.0 66.0 78.0 12:39:56 INFO - PROCESS | 1683 | 12:39:56 INFO - PROCESS | 1683 | [#10] stanford-crypto-aes Cycles:10 Average:88.30 Median:85.00 stddev:10.83 (12.7%) stddev-sans-first:11.16 12:39:56 INFO - PROCESS | 1683 | Values: 81.0 89.0 81.0 101.0 72.0 91.0 99.0 103.0 75.0 91.0 12:39:56 INFO - PROCESS | 1683 | 12:39:56 INFO - PROCESS | 1683 | [#11] stanford-crypto-ccm Cycles:10 Average:148.50 Median:152.00 stddev:13.57 (8.9%) stddev-sans-first:14.36 12:39:56 INFO - PROCESS | 1683 | Values: 146.0 150.0 145.0 144.0 154.0 143.0 118.0 158.0 157.0 170.0 12:39:56 INFO - PROCESS | 1683 | 12:39:56 INFO - PROCESS | 1683 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.80 Median:168.00 stddev:12.51 (7.4%) stddev-sans-first:13.15 12:39:56 INFO - PROCESS | 1683 | Values: 168.0 206.0 166.0 167.0 181.0 168.0 167.0 167.0 166.0 172.0 12:39:56 INFO - PROCESS | 1683 | 12:39:56 INFO - PROCESS | 1683 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.00 Median:68.50 stddev:9.79 (14.3%) stddev-sans-first:5.27 12:39:56 INFO - PROCESS | 1683 | Values: 95.0 81.0 70.0 66.0 68.0 64.0 63.0 68.0 66.0 69.0 12:39:56 INFO - PROCESS | 1683 | -------- Summary: end -------- 12:39:56 INFO - PROCESS | 1683 | 12:39:57 INFO - TEST-INFO | 1683: exit 0 12:39:57 INFO - TEST-OK | kraken | took 59231ms 12:39:57 INFO - SUITE-END | took 1170s 12:39:58 INFO - Completed test suite (00:19:30) 12:39:58 INFO - javascript benchmark 12:39:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2821.4639218548486, 3182.663281421803, 3194.9361012779746, 3415.2486452980343, 3442.156671615455, 5696.601237913337, 5712.22884910537, 5746.827595172141, 5747.195392645033, 5767.221425107609, 5665.405132461084, 5687.573431992597, 5718.628289161202, 5751.166080918264, 5755.935151996873, 4508.098380323941, 4994.175960966436, 5278.524932756051, 5324.520793128618, 5363.6245462817615, 5126.282320475137, 5258.605604579653, 5270.762815673284, 5272.551833094191, 5273.551748101416, 5165.535101840834, 5205.739713014353, 5218.243354713564, 5231.11071117909, 5324.520793128618, 5251.868703282429, 5287.735613219362, 5318.680879147263, 5326.946730532683, 5348.251244825728, 4708.623310135181, 5117.360329958755, 5164.14791559396, 5213.582913366921, 5246.081935661244, 5327.573794096463, 5456.236126942231, 5559.388467268597, 5610.214569960209, 5640.238567793336, 6178.814635560941, 6232.277706474024, 6241.594296370767, 6259.937400625981, 6268.341824108448, 6269.561130720851, 6302.558820882494, 6306.085617585424, 6311.368863113697, 6321.873562528724, 5621.100623900197, 5729.541636669056, 5781.739821707991, 5795.478406943395, 5835.124731290341, 5368.355797304349, 6093.0, 6295.464885484694, 6319.8104056878365, 6337.809865704036, 5982.132590774313, 5998.070299103646, 6154.446099851035, 6156.753729850845, 6171.290301615371, 6164.321924588292, 6231.2522497300615, 6253.1245625612455, 6289.55973081889, 6301.275353334425, 6192.411720886508, 6218.875622487525, 6237.625742455376, 6237.96881015592, 6243.875122497525, 6036.63780173191, 6151.2618485781095, 6250.937490625127, 6256.812295631138, 6267.811965641038, 4438.378626992224, 4895.216765317532, 4942.1104201244125, 4963.10664080469, 4963.478834722302, 4431.556844315543, 4447.421835161473, 4451.354553589711, 4454.420925279693, 4503.324501324828, 5072.644914855925, 5522.116461366242, 5723.141528770718, 5803.65178089316, 5813.94186058134, 6228.47058000066, 6246.156768836149, 6249.0938813871735, 6255.499560035186, 6276.968615156895, 5804.361520232771, 5828.184054232411, 5829.650220986754, 5839.9124013139835, 5863.853403664857, 5333.759980800874, 5358.464153584689, 5407.675539467645, 5445.346558413015, 5508.20131080991, 6331.556791024584, 6409.166808314889, 6417.679116044273, 6430.971044632836, 6435.549511534241, 5122.436531981404, 5173.430922598586, 6175.3515880831865, 6225.439710425902, 6321.905171422524, 6219.253689557282, 6314.968425157845, 6340.619562826245, 6363.809085727436, 6415.230172379344, 6139.0, 6160.137580738789, 6161.599496032743, 6191.783287584913, 6243.344448832807, 5797.985352563369, 5919.6744179070565, 6175.505959523138, 6177.289611694712, 6199.194104766351, 5876.706164691834, 6061.393860613904, 6088.51291896656, 6105.358937311518, 6109.725062372293, 5528.170774383874, 6122.245686735438, 6148.877022459436, 6151.6001459904965, 6174.475169610633, 5846.649201047951, 5996.430339117776, 6059.666718330534, 6072.574919755575, 6091.969540152271, 5007.424146223086, 6021.42796434338, 6059.787907423219, 6125.264968203844, 6183.319834818077, 5983.970080149571, 6017.976943919457, 6032.668203248863, 6041.546883983631, 6059.454649081604], "value": 5665.38789640132, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [517.4256575201528, 522.0733198572535, 541.2638811216744, 568.579251353998, 592.1857446011734, 37709.24581508372, 38914.416283755694, 38929.0, 38976.805115974414, 39044.414333784945, 40362.192756144934, 40927.59072409275, 40939.79530102353, 40954.0, 41070.38394424086, 9368.625254989809, 9611.95194024029, 9616.663416780415, 9624.37441566296, 9653.855192172123, 9353.0, 9544.0, 9560.904390956106, 9570.234381249484, 9578.0, 9524.904750952472, 9576.90423095767, 9589.760255993586, 9595.80808383832, 9597.0, 9506.95423503414, 9576.186024187953, 9595.616175353047, 9612.615495380176, 9616.374935629161, 9577.760555986086, 9636.180924621416, 9664.130228279419, 9681.128698417177, 9688.0, 38727.80636096802, 39168.608313917066, 39207.0, 39217.41173882394, 39333.60666393328, 3141.2146963259183, 3279.770416070876, 3299.5875515560556, 3301.4552598821165, 3314.105191598279, 28462.85768571144, 28570.714292857014, 28872.8556357219, 28874.422511549652, 29203.977860774772, 2733.439644872796, 2739.0960982875804, 2739.356251280967, 2755.256080858157, 2792.5113105206506, 486138.0, 490157.99999999645, 493422.0, 495519.0, 499185.5040724773, 59183.0, 59315.81368372695, 59721.402785971586, 59929.0, 59991.0, 59070.0, 63084.79203227957, 63410.0, 67551.66224168848, 68616.0, 17150.742738858928, 17276.913615431844, 17278.308867645406, 17364.739528907077, 17377.957322560687, 13128.803067953988, 13149.474021039052, 13248.735025299633, 13250.403731832092, 13265.867341326657, 19207.423777286705, 19721.94795998759, 19811.603767924847, 19868.0, 19917.402477925687, 6680.766173184013, 8164.469014605373, 8183.468074575134, 8211.384146189059, 8253.917460825434, 745.5265906149634, 953.1659797677032, 980.5587485631495, 1027.897210278981, 1082.4587706146926, 29596.852015739783, 30217.848910755307, 30268.0, 30718.69281307203, 30941.071767847, 1091.4215465803138, 1112.8386383974278, 1130.4799792095728, 1159.3855256713955, 1217.6834023154045, 15141.0, 15346.848986326071, 15500.0, 15556.922215388851, 15734.370625175094, 638504.0, 640507.0, 651574.0, 652108.0, 652665.0, 47256.527434725904, 54252.72873635607, 60864.478388039766, 60992.085118723255, 61199.69400152971, 1476.269246722879, 1496.1098146602972, 1502.241368109098, 1505.1796770759963, 1508.8113985751781, 29468.410631786814, 29928.401431970804, 29978.0, 30412.39175216439, 30556.694433055385, 50403.49596503988, 50925.0, 51196.488035119175, 51383.68773405131, 51442.22836657528, 576052.0, 579361.0, 579505.0, 580199.0, 580524.0, 26499.205023849314, 26647.46705065849, 26799.732002679724, 27074.1877743668, 27099.86450067737, 45696.77151614353, 46411.53588464072, 47113.293300599835, 47684.52315476801, 47696.28455573239, 37370.626293736714, 38107.23785524329, 38111.23777524378, 38264.42603361008, 38634.0, 37628.0, 37835.243295134496, 37894.431583525715, 38096.61903380931, 38630.6136938627, 33462.330753385286, 37488.25023499569, 37950.810245949695, 38114.0, 38758.61241387663, 30670.079897603107, 31207.375852483277, 31670.841645791625, 31689.683103169595, 31828.681713183498, 19778.90110549438, 24078.87960560186, 24273.75726242715, 24478.898449569457, 24529.632055519538], "value": 19925.773437658747, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.811514530598, 399.5684660566587, 403.30228704341505, 411.8929078439606, 413.66286476521634, 14901.776473352907, 20296.59406811863, 20626.071826767795, 21034.94825258737, 21128.4717882053, 18958.71561926572, 19095.331663391786, 19295.517612059706, 19939.501512462197, 20261.290854820083, 5387.676739395629, 5557.0, 5797.826065218051, 5834.5915785895, 5846.824595262149, 5713.457221563945, 5800.854978625547, 5826.980278451275, 5827.475527202551, 5828.883422331551, 5542.63972841766, 5750.856228594298, 5841.182234487154, 5843.707814609274, 5850.531957443415, 5783.6240644358195, 5819.563532735019, 5825.533957283428, 5829.941700582982, 5841.737121829507, 5053.797848086072, 5090.9236361454605, 5138.0, 5145.974270128625, 5162.6128040397125, 25709.0, 26018.86990565054, 26411.867940660173, 26513.86743066272, 26566.6015009775, 2138.700581918533, 2584.961225581599, 2607.413332000304, 2666.200139958024, 2911.505044142501, 13814.970104448319, 14654.487092951802, 14819.184944828137, 14991.475298364505, 15144.318505667272, 1663.1850393307193, 1669.0986867091876, 1688.5356526955063, 1702.7190513565247, 1710.136381127535, 113952.86047139505, 117515.0, 117773.0, 119159.40420297929, 119546.40226798897, 10667.73330666724, 11001.669949901516, 11163.046466868427, 11257.493412796444, 11359.829602555968, 14157.362918668685, 14181.645458863612, 14237.85762142386, 14266.928665356607, 14268.357923893449, 8700.477971321741, 8832.911670883337, 8842.027376988526, 8901.465912045298, 8915.37592368528, 8886.644534218689, 8959.41763785352, 8961.955190224007, 9077.500737459502, 9083.227925626263, 6644.8671026579195, 6701.731930722716, 6775.830604234933, 6782.355676210752, 6816.727330906709, 1774.7870255569414, 1883.3878989328523, 1912.7991560886185, 1914.0429785107447, 1934.951626209356, 904.0552622509493, 958.678842587741, 1013.7871047080196, 1018.2566726289701, 1035.5754140802235, 20814.0, 20867.582648346946, 20931.47671308199, 21096.261630843153, 21155.25956591512, 823.9011318641801, 959.4195511715451, 988.1008282462924, 1003.1573478278135, 1068.4978060311641, 9223.815523689489, 11375.829362559569, 11379.544818207345, 11379.658610241706, 11419.429028548539, 612697.0, 623615.0, 627111.0, 643906.0, 644767.0, 115750.84249157569, 116441.0, 117059.41470292765, 119691.0, 119894.0, 1016.3393794033938, 1038.444432228754, 1060.9151267898549, 1061.0291583201285, 1064.6486659402378, 18165.455036348932, 18583.81416185821, 18633.9068304663, 18757.718634220226, 18983.71524427162, 37944.0, 40893.795531023345, 51468.48531514637, 52127.73936130295, 52380.52434456929, 73368.63315683388, 74569.88145177935, 74695.62652186921, 76430.6178469104, 79783.0, 8056.83886322282, 8096.2308580687095, 8211.888854012659, 8287.792805179677, 8323.334133269445, 6938.93061069403, 9962.452065136487, 12540.372981351078, 12615.432305546456, 13071.607851764462, 10990.285631433931, 11077.944610276896, 11210.88789112131, 11342.886571134182, 11522.366269855238, 10771.730706732395, 10902.528158698475, 11016.504257308601, 11053.502592383202, 11196.608118715805, 11304.60433884843, 11336.206465547333, 11558.826617600575, 11578.478968446609, 11774.116941229271, 8071.273585377344, 8157.0, 8166.754997350089, 8186.918130818854, 8336.154872632454, 11185.161112916727, 11236.325820450798, 11303.604373846876, 11484.88515114838, 11510.079193664655], "value": 9730.492982077782, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1567.0910871694418, 1584.516722399668, 1813.3562585282225, 1885.1893685715154, 1987.195185949691, 7979.361651067915, 8583.184597463247, 8711.869321960168, 8916.554172291375, 8933.330000249984, 8647.740567782961, 8677.47935123893, 8768.342374321935, 8771.561421928895, 8821.647134114628, 2008.7689915659666, 2376.738558758535, 2464.6549483072295, 2473.6536884836137, 2495.6131799571094, 2454.1778504201116, 2475.121331927167, 2475.715292741331, 2496.151308555091, 2500.6374075759004, 2459.102427613926, 2471.7033955925313, 2475.0, 2497.7502249774966, 2503.5368456835545, 2026.5035066408677, 2449.8040156787415, 2461.79074778644, 2464.581021226396, 2476.950460990779, 2451.117597664839, 2466.728659847415, 2469.123461171276, 2470.419451428913, 2484.714257860351, 8819.735407937773, 8866.246369058637, 8930.151635594673, 8953.283737301, 8954.37319387643, 5393.946060539383, 5470.863228419282, 5501.14732216507, 5510.614257002011, 5567.025770490189, 2955.7044295570486, 3538.0800991742076, 3544.663256990582, 3579.8210089495683, 3633.3823250047553, 906.5014242166782, 1056.3292309383519, 1072.0565902006263, 1074.425182527352, 1087.6682611803396, 11410.885891141066, 11629.418529073513, 11677.708057298636, 11681.007114395286, 11686.0, 3604.4593311003036, 4702.929456058162, 4717.268823332389, 4727.125481785881, 4781.784819683053, 9322.25421966241, 9324.487153206639, 9326.673566425143, 9327.0, 9356.0, 5359.598030147755, 5368.32895888014, 5370.194470829329, 5385.084535628953, 5437.945620543823, 4431.911361772746, 4897.804087836446, 4988.27669987857, 4989.750512474435, 4992.226204938241, 3864.381698928158, 3899.1811719538646, 3930.6069393060466, 3952.4664170336637, 4031.4355990161403, 1044.6239353832614, 1230.390956476532, 1237.294741997053, 1242.3415589737454, 1254.2286493806425, 846.7713717296295, 940.1209868772719, 960.582146766159, 964.6961207219704, 969.9321047526605, 5719.512926639104, 5747.224124743189, 5766.389842689471, 5844.1233814927255, 5896.115582662635, 457.82602611007604, 471.44134200971956, 477.2364217252368, 478.3255609590461, 480.0878331170803, 3927.842886284574, 3934.7245692801803, 3976.900577485586, 4005.819738111792, 4126.711130220856, 10532.684019479428, 10823.350598964087, 11017.83473247902, 11022.0, 11038.724031899266, 10826.67519974402, 10878.945605271923, 10884.945575272073, 10919.543102801807, 10919.836202456969, 8344.541050242173, 8370.958145209357, 8402.537860417735, 8427.199416055464, 8514.78713032179, 5425.13197888336, 5427.3215848019, 5492.313460817398, 5524.309461317335, 5616.550675945996, 9479.194268487256, 9682.806343872942, 9688.370255933341, 9724.319297649097, 9860.211183105192, 8214.876776848472, 9458.24334053288, 9650.806983860424, 9764.951175244078, 9962.900370996198, 6006.639601623917, 6893.103896493424, 7597.848043039219, 7638.885416718865, 7717.151113377637, 8376.32989360862, 8383.706570270011, 8388.454750441202, 8416.621252043795, 8455.154484551742, 7312.96343518279, 7388.408927285912, 7487.47587668858, 7618.123915749759, 7635.42734294919, 7254.854902902018, 7324.267573242633, 7327.816804579928, 7397.445191610758, 7438.5536867788105, 6941.375276225164, 6978.337057979484, 6996.895046574204, 7043.964780176067, 7052.118485189351, 4852.975735121302, 4924.0, 4932.087563800708, 4983.426905905867, 5021.924671130009, 4993.80024799004, 5070.873228169326, 5074.720890351066, 5087.287779710918, 5158.767855446439], "value": 4512.00373601506, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [256.4025819839775, 275.5370976759045, 308.9274020605159, 314.4198952931841, 324.78239579481743, 5409.702466364353, 6685.097511835901, 6713.093732346132, 6744.898826517606, 6781.593104413739, 5318.787248510064, 6632.038354438616, 6639.0, 6643.302453242412, 6644.0, 4783.9760801196035, 4858.101251268509, 4955.430125535573, 4977.129002424579, 4984.651074424792, 4916.041371932474, 4960.727160006199, 4972.353594032788, 4978.406909788869, 4991.151504244287, 4933.679310844802, 4936.876578085541, 4941.728204948744, 4944.33251511045, 4972.502749725017, 4912.7297998610065, 4930.5069493050405, 4942.53045960635, 4954.826581069663, 4999.825006124768, 3869.7678139311734, 3892.1631849152286, 4281.764502952336, 4282.122164956177, 4284.957150428487, 6278.717457714368, 6411.326810684857, 6462.450691691212, 6482.189726284215, 6488.6106833589665, 5856.590038697293, 5909.645421274738, 5916.4675179233645, 5916.674582897939, 5941.613795103348, 7177.46169037319, 7194.0, 7207.89188162178, 7222.061132052852, 7242.818929526751, 4794.0, 4794.065157294329, 4797.376341075673, 4797.736124513151, 4804.0, 7337.559746415232, 7597.126330472011, 7628.351590114847, 7630.122535908331, 7633.2366763323225, 4098.344264917599, 4757.024809913959, 4844.8304309349005, 4848.878778030577, 4864.683795553277, 4908.116539022939, 4945.4312754033745, 4958.851234462972, 4960.553550180501, 4966.627502937295, 4850.95149048512, 5753.90675771602, 5839.854003649943, 5851.502622277069, 5856.502197313191, 7222.74720384784, 7232.276772322725, 7237.167725711505, 7238.855222895512, 7250.166230883516, 1705.4627792245403, 1730.7057800174, 1731.3680506614994, 1733.9219735111951, 1735.1497766094776, 853.9794945040617, 1029.8558201851747, 1042.6507120114695, 1043.1498328861953, 1106.3527836215742, 6663.566868153554, 6701.966490167615, 6708.725342184969, 6714.664266786641, 6726.596404215763, 7285.526440781332, 7445.404367650575, 7460.440466964999, 7475.252474752481, 7477.401807855358, 6637.933620663828, 6799.830004249834, 6800.183977922582, 6801.863962720718, 6812.352826481576, 3947.1908258806875, 3993.780342081155, 4003.159336539301, 4013.819378127991, 4017.397390391465, 7872.842543149105, 7888.171741967126, 7898.052233731989, 7912.49662564111, 7926.722564710323, 6920.9653951729915, 7209.495335326476, 7303.89044164338, 7740.574268415291, 7791.4156438267355, 7844.862494938317, 7881.330086942674, 7921.722739704082, 7922.564258965812, 7924.48490848093, 7474.2525747424825, 7492.925070749441, 7525.36034437079, 7528.548287102792, 7556.8866467001935, 6293.590916590407, 6332.746690132343, 7363.889541656772, 7377.5204611700065, 7393.963030184815, 6344.68276586178, 7619.69521219145, 7862.174471680391, 7865.292123708665, 7910.2089791020435, 3842.481265029185, 4661.20759470884, 4682.531746825426, 4687.367205427224, 4692.577668009895, 5280.762365693477, 7387.0, 7451.552906825608, 7476.962615186889, 7479.214682458263, 5905.616134951214, 7111.7866464006165, 7119.5372300802355, 7236.38490728294, 7253.165885923187, 5943.554233432596, 7177.892331614926, 7185.676644551112, 7189.065421495172, 7235.891461628185, 6015.608985415934, 6703.530752847449, 7039.296070392919, 7050.435965122675, 7072.0, 7177.712891484282, 7218.963905180441, 7227.241139680258, 7229.168645605822, 7264.527805692613, 6996.0, 7046.295370462913, 7051.929480705127, 7058.61177635235, 7072.71709131629], "value": 5208.89104764838, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [650.9739610415583, 669.4242951062083, 690.3821080133281, 729.6570611812447, 759.2217976574011, 14666.12003279804, 14855.331510082045, 15055.999999999985, 15114.697706045872, 15121.697566048673, 13514.662133446669, 13571.660708482294, 13588.184708917472, 13610.775030247276, 13611.047226694136, 744.6314074533086, 910.7222297199352, 923.6028507741669, 944.0182210501071, 951.4291425144893, 927.0729270729271, 939.6100618243421, 946.1059298962483, 948.6205517792869, 950.3632566180676, 986.1174249047099, 992.8758905136858, 992.885818130917, 996.0587245053428, 996.5814357969634, 955.5126885288483, 961.8509131084693, 986.8174387738255, 991.9801603967952, 994.3139233928603, 689.7103216648969, 708.6810935079235, 730.5288089182501, 733.9559626422432, 734.673070483635, 13853.861461385459, 13960.511382101578, 14124.152550846879, 14243.0, 14250.358733857001, 12539.686507837287, 12626.936865315707, 12637.115401921868, 12693.619191424179, 12789.680257993532, 9800.215982721365, 9973.952734962797, 10051.59793608262, 10067.798644027152, 10100.292979491438, 3517.6482351764876, 3522.84147213376, 3554.4668299754985, 3578.5169002184634, 3732.850685972558, 80295.0, 82066.58966705189, 83677.0, 83756.58121709352, 83810.58094709487, 7900.9604951976025, 9333.300002499704, 9439.0, 9445.76385590352, 9785.608575657036, 21587.460313492287, 21593.24423645165, 21597.784022159576, 21696.24063157782, 21777.34667959964, 7359.337659610661, 7443.330100291, 7475.177730449646, 7539.359154471822, 7570.432217583703, 3955.3473676843573, 3959.5050618672667, 4064.5935406459707, 4076.184763047402, 4096.221717873654, 3365.6297807241185, 3405.233822389992, 3437.9656203438144, 3445.2937147885123, 3449.413599688059, 608.0605464557182, 610.5268416976878, 611.8868009418272, 617.9134921111048, 620.3455354600849, 554.4898693202183, 573.6844735395516, 597.3697748874891, 603.1947350287415, 607.5504126946028, 5772.163036359704, 6810.591364518045, 7015.89476157858, 8000.15998320168, 8143.959280203561, 553.7093026161217, 574.454268444979, 574.6695650001266, 596.5049009322252, 607.7447472061745, 3152.4956007038763, 3742.8315725792404, 3868.3810590305416, 3876.903077423087, 3927.489426374553, 81473.96315092052, 82123.0, 92487.0, 93057.0, 93884.12231755327, 56751.0, 60454.0, 76409.23590764133, 76825.0, 78982.21017789864, 63736.0, 68529.97205041928, 68623.0, 69189.3081069193, 69908.0, 8408.95795521043, 8482.787930301793, 8550.230479256898, 8617.95691021541, 8788.736337909873, 45339.546604533534, 48368.7581562104, 48993.02013959772, 49783.751081244365, 49848.50151498439, 62449.68775156095, 64055.6797216011, 65283.67358163179, 65756.0, 66607.00089498758, 10762.677119686421, 10825.0, 11104.944475277573, 11108.888911110786, 11167.274127181707, 11804.881951180378, 11990.400479975791, 12053.397330133634, 12061.939690301493, 12430.254184748945, 10470.534125222628, 11129.054030407506, 11175.664730058112, 11405.828912566483, 12029.879701202875, 10348.0, 11181.888181118085, 11300.0, 11547.595834145764, 11669.474873630877, 10762.946185269024, 10858.0, 11106.555737770399, 11186.159970468245, 11306.94346528262, 9543.733811516073, 9719.416834989923, 9768.316217864682, 9861.852072219066, 9879.50602469888, 9582.377145485521, 9684.983076777118, 9776.804463910825, 9822.165115965223, 9886.406815591088], "value": 6815.596761397214, "unit": "score"}], "name": "dromaeo_css", "value": 7485.820991252552, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 119.0, 107.0, 116.0, 117.0, 118.0, 108.0, 116.0, 115.0, 118.0], "value": 114.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 124.0, 157.0, 152.0, 120.0, 140.0, 122.0, 171.0, 191.0, 153.0], "value": 148.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 194.0, 197.0, 197.0, 195.0, 194.0, 195.0, 195.0, 197.0, 194.0], "value": 195.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 74.0, 74.0, 141.0, 58.0, 57.0, 58.0, 63.0, 142.0, 83.0], "value": 81.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 82.0, 129.0, 80.0, 79.0, 80.0, 115.0, 79.0, 80.0, 118.0], "value": 91.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 94.0, 127.0, 86.0, 90.0, 86.0, 90.0, 87.0, 88.0, 85.0], "value": 92.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 96.0, 149.0, 95.0, 127.0, 148.0, 100.0], "value": 109.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 97.0, 90.0, 92.0, 98.0, 91.0, 91.0, 94.0, 91.0, 91.0], "value": 93.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 65.0, 66.0, 67.0, 65.0, 66.0, 68.0, 81.0, 66.0, 61.0], "value": 66.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 104.0, 107.0, 89.0, 76.0, 73.0, 68.0, 67.0, 66.0, 78.0], "value": 79.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 89.0, 81.0, 101.0, 72.0, 91.0, 99.0, 103.0, 75.0, 91.0], "value": 88.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 150.0, 145.0, 144.0, 154.0, 143.0, 118.0, 158.0, 157.0, 170.0], "value": 148.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 206.0, 166.0, 167.0, 181.0, 168.0, 167.0, 167.0, 166.0, 172.0], "value": 172.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 81.0, 70.0, 66.0, 68.0, 64.0, 63.0, 68.0, 66.0, 69.0], "value": 71.0, "unit": "score"}], "name": "kraken", "value": 1552.8, "alertThreshold": 2.0}]} 12:39:58 INFO - Return code: 0 12:39:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 12:39:58 ERROR - # TBPL SUCCESS # 12:39:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:39:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:39:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:39:58 INFO - 2016-05-30 12:39:58.162 screenresolution[1690:11039] starting screenresolution argv=screenresolution get 12:39:58 INFO - 2016-05-30 12:39:58.164 screenresolution[1690:11039] Display 0: 1600x1200x32@60 12:39:58 INFO - 2016-05-30 12:39:58.171 screenresolution[1691:11041] starting screenresolution argv=screenresolution list 12:39:58 INFO - Available Modes on Display 0 12:39:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:39:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:39:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:39:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:39:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:39:58 INFO - Intel Iris: 12:39:58 INFO - Chipset Model: Intel Iris 12:39:58 INFO - Type: GPU 12:39:58 INFO - Bus: Built-In 12:39:58 INFO - VRAM (Dynamic, Max): 1536 MB 12:39:58 INFO - Vendor: Intel (0x8086) 12:39:58 INFO - Device ID: 0x0a2e 12:39:58 INFO - Revision ID: 0x0009 12:39:58 INFO - Displays: 12:39:58 INFO - Display: 12:39:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:39:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:39:58 INFO - Display Serial Number: 1600x1200 60 12:39:58 INFO - Main Display: Yes 12:39:58 INFO - Mirror: Off 12:39:58 INFO - Online: Yes 12:39:58 INFO - Rotation: Supported 12:39:58 INFO - Return code: 0 12:39:58 INFO - Running post-action listener: _resource_record_post_action 12:39:58 INFO - Running post-run listener: _resource_record_post_run 12:39:59 INFO - Total resource usage - Wall time: 1203s; CPU: 53.0%; Read bytes: 32316928; Write bytes: 654343168; Read time: 326; Write time: 1986 12:39:59 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 342880768; Write bytes: 337519616; Read time: 26492; Write time: 618 12:39:59 INFO - run-tests - Wall time: 1173s; CPU: 53.0%; Read bytes: 31387136; Write bytes: 297437184; Read time: 313; Write time: 1312 12:39:59 INFO - Running post-run listener: _upload_blobber_files 12:39:59 INFO - Blob upload gear active. 12:39:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:39:59 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 12:39:59 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'] 12:39:59 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 12:39:59 INFO - (blobuploader) - INFO - Open directory for files ... 12:39:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:39:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:39:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:40:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:40:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:40:00 INFO - (blobuploader) - INFO - Done attempting. 12:40:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:40:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:40:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:40:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:40:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:40:01 INFO - (blobuploader) - INFO - Done attempting. 12:40:01 INFO - (blobuploader) - INFO - Iteration through files over. 12:40:01 INFO - Return code: 0 12:40:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:40:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:40:01 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fe8ab1c8882c3d49ccb2442952138bc05aaeafdd6ae0d7304537f6713d2b123b404421a7a9a30ad6ecfa8e92e436c509289d9d3d87d8c88edb54d74967283266", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3e71ce3f73000188e881fcd9d39aed1009258d784a0b8b8616ea7b6bcfaf0777ed19d3210db515c810452a86ffa25479d12727e71495bb4ee8ea434a61564e48"} 12:40:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:40:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:40:01 INFO - Contents: 12:40:01 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fe8ab1c8882c3d49ccb2442952138bc05aaeafdd6ae0d7304537f6713d2b123b404421a7a9a30ad6ecfa8e92e436c509289d9d3d87d8c88edb54d74967283266", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3e71ce3f73000188e881fcd9d39aed1009258d784a0b8b8616ea7b6bcfaf0777ed19d3210db515c810452a86ffa25479d12727e71495bb4ee8ea434a61564e48"} 12:40:01 INFO - Running post-run listener: copy_logs_to_upload_dir 12:40:01 INFO - Copying logs to upload dir... 12:40:01 INFO - mkdir: /builds/slave/test/build/upload/logs 12:40:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1281.768709 ========= master_lag: 3.27 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2016-05-30 12:40:05.182183) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 12:40:05.183300) ========= 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.eZEdY1ZsC9/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.gTjW51ICda/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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/fe8ab1c8882c3d49ccb2442952138bc05aaeafdd6ae0d7304537f6713d2b123b404421a7a9a30ad6ecfa8e92e436c509289d9d3d87d8c88edb54d74967283266", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3e71ce3f73000188e881fcd9d39aed1009258d784a0b8b8616ea7b6bcfaf0777ed19d3210db515c810452a86ffa25479d12727e71495bb4ee8ea434a61564e48"} build_url:https://queue.taskcluster.net/v1/task/SxAtSq2hSWKDPgiN5vy_4Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011000 build_url: 'https://queue.taskcluster.net/v1/task/SxAtSq2hSWKDPgiN5vy_4Q/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/fe8ab1c8882c3d49ccb2442952138bc05aaeafdd6ae0d7304537f6713d2b123b404421a7a9a30ad6ecfa8e92e436c509289d9d3d87d8c88edb54d74967283266", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3e71ce3f73000188e881fcd9d39aed1009258d784a0b8b8616ea7b6bcfaf0777ed19d3210db515c810452a86ffa25479d12727e71495bb4ee8ea434a61564e48"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 12:40:05.218194) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 12:40:05.218500) ========= 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.eZEdY1ZsC9/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.gTjW51ICda/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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.005226 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 12:40:05.270466) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 12:40:05.272781) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 12:40:05.273123) ========= ========= Total master_lag: 3.41 =========