builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0053 starttime: 1460895262.32 results: success (0) buildid: 20160417030601 builduid: c47ad0463ea84d4994e6432533896f26 revision: 1da1937a9e03154ae7c60089f2dcf5ad9ee20fa3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-17 05:14:22.323580) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-17 05:14:22.326732) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-17 05:14:22.327006) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-17 05:14:22.480888) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-17 05:14:22.481209) ========= 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.J3GLF0xqQJ/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.6qSqrT1wbF/Listeners TMPDIR=/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-17 05:14:22-- 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% 193M=0s 2016-04-17 05:14:23 (193 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.816224 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-17 05:14:23.351300) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-17 05:14:23.351603) ========= 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.J3GLF0xqQJ/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.6qSqrT1wbF/Listeners TMPDIR=/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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.028328 ========= master_lag: 0.20 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-17 05:14:23.579547) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-17 05:14:23.579927) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 1da1937a9e03154ae7c60089f2dcf5ad9ee20fa3 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 1da1937a9e03154ae7c60089f2dcf5ad9ee20fa3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.J3GLF0xqQJ/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.6qSqrT1wbF/Listeners TMPDIR=/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-17 05:14:23,692 truncating revision to first 12 chars 2016-04-17 05:14:23,692 Setting DEBUG logging. 2016-04-17 05:14:23,692 attempt 1/10 2016-04-17 05:14:23,692 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/1da1937a9e03?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-17 05:14:24,336 unpacking tar archive at: mozilla-central-1da1937a9e03/testing/mozharness/ program finished with exit code 0 elapsedTime=0.883491 ========= master_lag: 0.10 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-17 05:14:24.567396) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-17 05:14:24.567691) ========= script_repo_revision: 1da1937a9e03154ae7c60089f2dcf5ad9ee20fa3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-17 05:14:24.568408) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-17 05:14:24.568690) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-17 05:14:24.910961) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2016-04-17 05:14:24.911344) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.J3GLF0xqQJ/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.6qSqrT1wbF/Listeners TMPDIR=/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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 05:14:25 INFO - MultiFileLogger online at 20160417 05:14:25 in /builds/slave/test 05:14:25 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:14:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:14:25 INFO - {'append_to_log': False, 05:14:25 INFO - 'base_work_dir': '/builds/slave/test', 05:14:25 INFO - 'blob_upload_branch': 'Firefox', 05:14:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:14:25 INFO - 'branch': 'Firefox', 05:14:25 INFO - 'buildbot_json_path': 'buildprops.json', 05:14:25 INFO - 'config_files': ('talos/mac_config.py',), 05:14:25 INFO - 'default_actions': ('clobber', 05:14:25 INFO - 'read-buildbot-config', 05:14:25 INFO - 'download-and-extract', 05:14:25 INFO - 'populate-webroot', 05:14:25 INFO - 'create-virtualenv', 05:14:25 INFO - 'install', 05:14:25 INFO - 'run-tests'), 05:14:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:14:25 INFO - 'download_minidump_stackwalk': True, 05:14:25 INFO - 'download_symbols': 'ondemand', 05:14:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:14:25 INFO - 'tooltool.py': '/tools/tooltool.py', 05:14:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:14:25 INFO - '/tools/misc-python/virtualenv.py')}, 05:14:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:14:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:14:25 INFO - 'installer_path': 'installer.exe', 05:14:25 INFO - 'log_level': 'info', 05:14:25 INFO - 'log_name': 'talos', 05:14:25 INFO - 'log_to_console': True, 05:14:25 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:14:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:14:25 INFO - 'opt_config_files': (), 05:14:25 INFO - 'pip_index': False, 05:14:25 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:14:25 INFO - 'cmd': ('bash', 05:14:25 INFO - '-c', 05:14:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:14:25 INFO - 'enabled': True, 05:14:25 INFO - 'halt_on_failure': False, 05:14:25 INFO - 'name': 'check_screen_resolution'},), 05:14:25 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:14:25 INFO - 'cmd': ('bash', 05:14:25 INFO - '-c', 05:14:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:14:25 INFO - 'enabled': True, 05:14:25 INFO - 'halt_on_failure': False, 05:14:25 INFO - 'name': 'check_screen_resolution'},), 05:14:25 INFO - 'run_cmd_checks_enabled': True, 05:14:25 INFO - 'sps_profile': False, 05:14:25 INFO - 'sps_profile_interval': 0, 05:14:25 INFO - 'suite': 'dromaeojs-e10s', 05:14:25 INFO - 'system_bits': '32', 05:14:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:14:25 INFO - 'title': 't-yosemite-r7-0053', 05:14:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:14:25 INFO - 'use_talos_json': True, 05:14:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:14:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:14:25 INFO - 'work_dir': 'build'} 05:14:25 INFO - ##### 05:14:25 INFO - ##### Running clobber step. 05:14:25 INFO - ##### 05:14:25 INFO - Running pre-action listener: _resource_record_pre_action 05:14:25 INFO - Running main action method: clobber 05:14:25 INFO - rmtree: /builds/slave/test/build 05:14:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:14:26 INFO - Running post-action listener: _resource_record_post_action 05:14:26 INFO - ##### 05:14:26 INFO - ##### Running read-buildbot-config step. 05:14:26 INFO - ##### 05:14:26 INFO - Running pre-action listener: _resource_record_pre_action 05:14:26 INFO - Running main action method: read_buildbot_config 05:14:26 INFO - Using buildbot properties: 05:14:26 INFO - { 05:14:26 INFO - "project": "", 05:14:26 INFO - "product": "firefox", 05:14:26 INFO - "script_repo_revision": "production", 05:14:26 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:14:26 INFO - "repository": "", 05:14:26 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:14:26 INFO - "buildid": "20160417030601", 05:14:26 INFO - "stage_platform": "macosx64", 05:14:26 INFO - "basedir": "/builds/slave/test", 05:14:26 INFO - "nightly_build": "True", 05:14:26 INFO - "buildnumber": 244, 05:14:26 INFO - "slavename": "t-yosemite-r7-0053", 05:14:26 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 05:14:26 INFO - "platform": "yosemite_r7", 05:14:26 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:14:26 INFO - "branch": "mozilla-central", 05:14:26 INFO - "repo_path": "mozilla-central", 05:14:26 INFO - "revision": "1da1937a9e03154ae7c60089f2dcf5ad9ee20fa3", 05:14:26 INFO - "pgo_build": "False", 05:14:26 INFO - "builduid": "c47ad0463ea84d4994e6432533896f26", 05:14:26 INFO - "slavebuilddir": "test" 05:14:26 INFO - } 05:14:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 05:14:26 INFO - Running post-action listener: _resource_record_post_action 05:14:26 INFO - ##### 05:14:26 INFO - ##### Running download-and-extract step. 05:14:26 INFO - ##### 05:14:26 INFO - Running pre-action listener: _resource_record_pre_action 05:14:26 INFO - Running main action method: download_and_extract 05:14:26 INFO - mkdir: /builds/slave/test/build/tests 05:14: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')]} 05:14:26 INFO - https://queue.taskcluster.net/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:14:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 05:14:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 05:14:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 05:14:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 05:14:38 INFO - Downloaded 1199 bytes. 05:14:38 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 05:14:38 INFO - Using the following test package requirements: 05:14:38 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 05:14:38 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:14:38 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 05:14:38 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:14:38 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 05:14:38 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:14:38 INFO - u'jsshell-mac.zip'], 05:14:38 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:14:38 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 05:14:38 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 05:14:38 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:14:38 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 05:14:38 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:14:38 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 05:14:38 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:14:38 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 05:14:38 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 05:14:38 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 05:14:38 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:14:38 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')]} 05:14:38 INFO - https://queue.taskcluster.net/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:14:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:14:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:14:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:14:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:14:40 INFO - Downloaded 18032122 bytes. 05:14:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:14:40 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:14:43 INFO - Return code: 0 05:14:43 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:14:43 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')]} 05:14:43 INFO - https://queue.taskcluster.net/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:14:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:14:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:14:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 05:14:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:14:45 INFO - Downloaded 18032122 bytes. 05:14:45 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:14:45 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:14:48 INFO - Return code: 0 05:14:48 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')]} 05:14:48 INFO - https://queue.taskcluster.net/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:14:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 05:14:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 05:14:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 05:14:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:14:49 INFO - Downloaded 10841076 bytes. 05:14:49 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:14:49 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 05:14:49 INFO - Return code: 0 05:14: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')]} 05:14:49 INFO - https://queue.taskcluster.net/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 05:14:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:14:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:14:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 05:14:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 05:14:58 INFO - Downloaded 109987209 bytes. 05:14:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:14:58 INFO - mkdir: /builds/slave/test/properties 05:14:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:14:58 INFO - Writing to file /builds/slave/test/properties/build_url 05:14:58 INFO - Contents: 05:14:58 INFO - build_url:https://queue.taskcluster.net/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 05:14:59 INFO - Running post-action listener: _resource_record_post_action 05:14:59 INFO - Running post-action listener: set_extra_try_arguments 05:14:59 INFO - ##### 05:14:59 INFO - ##### Running populate-webroot step. 05:14:59 INFO - ##### 05:14:59 INFO - Running pre-action listener: _resource_record_pre_action 05:14:59 INFO - Running main action method: populate_webroot 05:14:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:14:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:14:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:14:59 INFO - '--tppagecycles', 05:14:59 INFO - '7'], 05:14:59 INFO - 'tests': ['tsvgm']}}, 05:14:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:14:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:14:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:14:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:14:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:14:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:14:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:14:59 INFO - 'talos_options': ['--disable-e10s'], 05:14:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:14:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:14:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:14:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:14:59 INFO - 'talos_options': ['--disable-e10s'], 05:14:59 INFO - 'tests': ['damp', 'tps']}, 05:14:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:14:59 INFO - 'tests': ['damp', 'tps']}, 05:14:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:14:59 INFO - 'tests': ['dromaeo_dom']}, 05:14:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:14:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:14:59 INFO - 'tests': ['a11yr', 05:14:59 INFO - 'ts_paint', 05:14:59 INFO - 'tpaint', 05:14:59 INFO - 'sessionrestore', 05:14:59 INFO - 'sessionrestore_no_auto_restore', 05:14:59 INFO - 'tabpaint']}, 05:14:59 INFO - 'other-e10s': {'tests': ['a11yr', 05:14:59 INFO - 'ts_paint', 05:14:59 INFO - 'tpaint', 05:14:59 INFO - 'sessionrestore', 05:14:59 INFO - 'sessionrestore_no_auto_restore', 05:14:59 INFO - 'tabpaint']}, 05:14:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:14:59 INFO - 'ts_paint', 05:14:59 INFO - 'tpaint', 05:14:59 INFO - 'sessionrestore', 05:14:59 INFO - 'sessionrestore_no_auto_restore', 05:14:59 INFO - 'tabpaint']}, 05:14:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:14:59 INFO - 'ts_paint', 05:14:59 INFO - 'tpaint', 05:14:59 INFO - 'sessionrestore', 05:14:59 INFO - 'sessionrestore_no_auto_restore', 05:14:59 INFO - 'tabpaint']}, 05:14:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:14:59 INFO - 'tests': ['a11yr', 05:14:59 INFO - 'ts_paint', 05:14:59 INFO - 'tpaint', 05:14:59 INFO - 'sessionrestore', 05:14:59 INFO - 'sessionrestore_no_auto_restore', 05:14:59 INFO - 'tabpaint']}, 05:14:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:14:59 INFO - 'tests': ['a11yr', 05:14:59 INFO - 'ts_paint', 05:14:59 INFO - 'tpaint', 05:14:59 INFO - 'sessionrestore', 05:14:59 INFO - 'sessionrestore_no_auto_restore', 05:14:59 INFO - 'tabpaint']}, 05:14:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:14:59 INFO - 'tests': ['tsvgx', 05:14:59 INFO - 'tsvgr_opacity', 05:14:59 INFO - 'tart', 05:14:59 INFO - 'tscrollx', 05:14:59 INFO - 'cart']}, 05:14:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:14:59 INFO - 'tsvgr_opacity', 05:14:59 INFO - 'tart', 05:14:59 INFO - 'tscrollx', 05:14:59 INFO - 'cart']}, 05:14:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:14:59 INFO - 'talos_options': ['--disable-e10s'], 05:14:59 INFO - 'tests': ['tp5o']}, 05:14:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:14:59 INFO - 'tests': ['tp5o']}, 05:14:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:14:59 INFO - 'talos_options': ['--disable-e10s', 05:14:59 INFO - '--xperf_path', 05:14:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:14:59 INFO - 'tests': ['tp5n']}, 05:14:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:14:59 INFO - 'talos_options': ['--xperf_path', 05:14:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:14:59 INFO - 'tests': ['tp5n']}}, 05:14:59 INFO - 'talos.zip': {'path': '', 05:14:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:14:59 INFO - Running post-action listener: _resource_record_post_action 05:14:59 INFO - ##### 05:14:59 INFO - ##### Running create-virtualenv step. 05:14:59 INFO - ##### 05:14:59 INFO - Running pre-action listener: _resource_record_pre_action 05:14:59 INFO - Running main action method: create_virtualenv 05:14:59 INFO - Creating virtualenv /builds/slave/test/build/venv 05:14:59 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 05:14:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:14:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:14:59 INFO - Using real prefix '/tools/python27' 05:14:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:15:00 INFO - Installing distribute.............................................................................................................................................................................................done. 05:15:03 INFO - Installing pip.................done. 05:15:03 INFO - Return code: 0 05:15:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:15:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:15:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:15:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:15:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:15:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:15:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:15:03 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 0x1077d1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077e1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ce94402f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107670e00>, '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 0x1074d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10766bb20>, '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.J3GLF0xqQJ/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.6qSqrT1wbF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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 05:15:03 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 05:15:03 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 05:15:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.J3GLF0xqQJ/Render', 05:15:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:15:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:15:03 INFO - 'HOME': '/Users/cltbld', 05:15:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:15:03 INFO - 'LOGNAME': 'cltbld', 05:15:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:15:03 INFO - 'MOZ_NO_REMOTE': '1', 05:15:03 INFO - 'NO_EM_RESTART': '1', 05:15:03 INFO - 'PAGER': '/bin/cat', 05:15:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:15:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:15:03 INFO - 'PWD': '/builds/slave/test', 05:15:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:15:03 INFO - 'SHELL': '/bin/bash', 05:15:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6qSqrT1wbF/Listeners', 05:15:03 INFO - 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/', 05:15:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:15:03 INFO - 'USER': 'cltbld', 05:15:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:15:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:15:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:15:03 INFO - 'XPC_FLAGS': '0x0', 05:15:03 INFO - 'XPC_SERVICE_NAME': '0', 05:15:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:15:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:15:03 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 05:15:03 INFO - Cleaning up... 05:15:03 INFO - Return code: 0 05:15:03 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:15:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:15:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:15:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:15:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:15:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:15:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:15:03 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 0x1077d1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077e1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ce94402f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107670e00>, '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 0x1074d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10766bb20>, '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.J3GLF0xqQJ/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.6qSqrT1wbF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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 05:15:03 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 05:15:03 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 05:15:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.J3GLF0xqQJ/Render', 05:15:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:15:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:15:03 INFO - 'HOME': '/Users/cltbld', 05:15:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:15:03 INFO - 'LOGNAME': 'cltbld', 05:15:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:15:03 INFO - 'MOZ_NO_REMOTE': '1', 05:15:03 INFO - 'NO_EM_RESTART': '1', 05:15:03 INFO - 'PAGER': '/bin/cat', 05:15:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:15:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:15:03 INFO - 'PWD': '/builds/slave/test', 05:15:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:15:03 INFO - 'SHELL': '/bin/bash', 05:15:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6qSqrT1wbF/Listeners', 05:15:03 INFO - 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/', 05:15:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:15:03 INFO - 'USER': 'cltbld', 05:15:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:15:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:15:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:15:03 INFO - 'XPC_FLAGS': '0x0', 05:15:03 INFO - 'XPC_SERVICE_NAME': '0', 05:15:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:15:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:15:03 INFO - Downloading/unpacking psutil>=0.7.1 05:15:03 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 05:15:03 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 05:15:03 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 05:15:03 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 05:15:03 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 05:15:03 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 05:15:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:15:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:15:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:15:06 INFO - Installing collected packages: psutil 05:15:06 INFO - Running setup.py install for psutil 05:15:06 INFO - building 'psutil._psutil_osx' extension 05:15:06 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 05:15:07 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 05:15:07 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 05:15:07 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 05:15:07 INFO - building 'psutil._psutil_posix' extension 05:15:07 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 05:15:07 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:15:07 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:15:07 INFO - ^ 05:15:07 INFO - 1 warning generated. 05:15:07 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 05:15:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:15:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:15:07 INFO - Successfully installed psutil 05:15:07 INFO - Cleaning up... 05:15:07 INFO - Return code: 0 05:15:07 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 05:15:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:15:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:15:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:15:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:15:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:15:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:15:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1077d1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077e1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ce94402f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107670e00>, '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 0x1074d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10766bb20>, '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.J3GLF0xqQJ/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.6qSqrT1wbF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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 05:15:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 05:15:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 05:15:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.J3GLF0xqQJ/Render', 05:15:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:15:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:15:07 INFO - 'HOME': '/Users/cltbld', 05:15:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:15:07 INFO - 'LOGNAME': 'cltbld', 05:15:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:15:07 INFO - 'MOZ_NO_REMOTE': '1', 05:15:07 INFO - 'NO_EM_RESTART': '1', 05:15:07 INFO - 'PAGER': '/bin/cat', 05:15:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:15:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:15:07 INFO - 'PWD': '/builds/slave/test', 05:15:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:15:07 INFO - 'SHELL': '/bin/bash', 05:15:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6qSqrT1wbF/Listeners', 05:15:07 INFO - 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/', 05:15:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:15:07 INFO - 'USER': 'cltbld', 05:15:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:15:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:15:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:15:07 INFO - 'XPC_FLAGS': '0x0', 05:15:07 INFO - 'XPC_SERVICE_NAME': '0', 05:15:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:15:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:15:07 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 05:15:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:15:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:15:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:15:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:15:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:10 INFO - Downloading mozsystemmonitor-0.0.tar.gz 05:15:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:15:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 05:15:10 INFO - Installing collected packages: mozsystemmonitor 05:15:10 INFO - Running setup.py install for mozsystemmonitor 05:15:10 INFO - Successfully installed mozsystemmonitor 05:15:10 INFO - Cleaning up... 05:15:10 INFO - Return code: 0 05:15:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:15:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:15:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:15:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:15:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:15:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:15:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:15:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1077d1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077e1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ce94402f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107670e00>, '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 0x1074d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10766bb20>, '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.J3GLF0xqQJ/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.6qSqrT1wbF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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 05:15:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:15:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:15:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.J3GLF0xqQJ/Render', 05:15:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:15:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:15:10 INFO - 'HOME': '/Users/cltbld', 05:15:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:15:10 INFO - 'LOGNAME': 'cltbld', 05:15:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:15:10 INFO - 'MOZ_NO_REMOTE': '1', 05:15:10 INFO - 'NO_EM_RESTART': '1', 05:15:10 INFO - 'PAGER': '/bin/cat', 05:15:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:15:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:15:10 INFO - 'PWD': '/builds/slave/test', 05:15:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:15:10 INFO - 'SHELL': '/bin/bash', 05:15:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6qSqrT1wbF/Listeners', 05:15:10 INFO - 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/', 05:15:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:15:10 INFO - 'USER': 'cltbld', 05:15:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:15:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:15:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:15:10 INFO - 'XPC_FLAGS': '0x0', 05:15:10 INFO - 'XPC_SERVICE_NAME': '0', 05:15:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:15:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:15:10 INFO - Downloading/unpacking blobuploader==1.2.4 05:15:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:15:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:15:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:15:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:15:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:13 INFO - Downloading blobuploader-1.2.4.tar.gz 05:15:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:15:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:15:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:15:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:15:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:15:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:15:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:15:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:15:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:15:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:15:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:15:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:15:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:14 INFO - Downloading docopt-0.6.1.tar.gz 05:15:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:15:14 INFO - Installing collected packages: blobuploader, requests, docopt 05:15:14 INFO - Running setup.py install for blobuploader 05:15:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:15:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:15:14 INFO - Running setup.py install for requests 05:15:15 INFO - Running setup.py install for docopt 05:15:15 INFO - Successfully installed blobuploader requests docopt 05:15:15 INFO - Cleaning up... 05:15:15 INFO - Return code: 0 05:15:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:15:15 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')]} 05:15:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:15:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:15:15 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')]} 05:15:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:15:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:15:15 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 0x1077d1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077e1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ce94402f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107670e00>, '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 0x1074d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10766bb20>, '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.J3GLF0xqQJ/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.6qSqrT1wbF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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 05:15:15 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 05:15:15 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 05:15:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.J3GLF0xqQJ/Render', 05:15:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:15:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:15:15 INFO - 'HOME': '/Users/cltbld', 05:15:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:15:15 INFO - 'LOGNAME': 'cltbld', 05:15:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:15:15 INFO - 'MOZ_NO_REMOTE': '1', 05:15:15 INFO - 'NO_EM_RESTART': '1', 05:15:15 INFO - 'PAGER': '/bin/cat', 05:15:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:15:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:15:15 INFO - 'PWD': '/builds/slave/test', 05:15:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:15:15 INFO - 'SHELL': '/bin/bash', 05:15:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6qSqrT1wbF/Listeners', 05:15:15 INFO - 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/', 05:15:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:15:15 INFO - 'USER': 'cltbld', 05:15:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:15:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:15:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:15:15 INFO - 'XPC_FLAGS': '0x0', 05:15:15 INFO - 'XPC_SERVICE_NAME': '0', 05:15:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:15:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:15:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:15:15 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-MrtSpy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:15:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:15:15 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-5vC0xB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:15:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:15:15 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-iYiPdI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:15:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:15:15 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-xqw23V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:15:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:15:16 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-QAivsa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:15:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:15:16 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-HKl2sh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:15:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:15:16 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-Xn3bDR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:15:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:15:16 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-ACPpOg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:15:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:15:16 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-wJ7JCi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:15:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:15:16 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-nNw9pq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:15:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:15:16 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-PrkGOO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:15:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:15:16 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-c1lj5N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:15:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:15:16 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-kv03wr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:15:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:15:16 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-lfsr1H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:15:17 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-PRnIjr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:15:17 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-RiKCHd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:15:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:15:17 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-wcUMIZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:15:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:15:17 INFO - Running setup.py install for manifestparser 05:15:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:15:17 INFO - Running setup.py install for mozcrash 05:15:17 INFO - Running setup.py install for mozdebug 05:15:17 INFO - Running setup.py install for mozdevice 05:15:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:15:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:15:17 INFO - Running setup.py install for mozfile 05:15:18 INFO - Running setup.py install for mozhttpd 05:15:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:15:18 INFO - Running setup.py install for mozinfo 05:15:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:15:18 INFO - Running setup.py install for mozInstall 05:15:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:15:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:15:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:15:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:15:18 INFO - Running setup.py install for mozleak 05:15:18 INFO - Running setup.py install for mozlog 05:15:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:15:18 INFO - Running setup.py install for moznetwork 05:15:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:15:18 INFO - Running setup.py install for mozprocess 05:15:19 INFO - Running setup.py install for mozprofile 05:15:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:15:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:15:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:15:19 INFO - Running setup.py install for mozrunner 05:15:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:15:19 INFO - Running setup.py install for mozscreenshot 05:15:19 INFO - Running setup.py install for moztest 05:15:19 INFO - Running setup.py install for mozversion 05:15:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:15:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:15:19 INFO - Cleaning up... 05:15:19 INFO - Return code: 0 05:15:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:15:19 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')]} 05:15:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:15:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:15:19 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')]} 05:15:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:15:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:15:19 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 0x1077d1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077e1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ce94402f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107670e00>, '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 0x1074d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10766bb20>, '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.J3GLF0xqQJ/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.6qSqrT1wbF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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 05:15:19 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 05:15:19 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 05:15:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.J3GLF0xqQJ/Render', 05:15:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:15:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:15:19 INFO - 'HOME': '/Users/cltbld', 05:15:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:15:19 INFO - 'LOGNAME': 'cltbld', 05:15:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:15:19 INFO - 'MOZ_NO_REMOTE': '1', 05:15:19 INFO - 'NO_EM_RESTART': '1', 05:15:19 INFO - 'PAGER': '/bin/cat', 05:15:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:15:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:15:19 INFO - 'PWD': '/builds/slave/test', 05:15:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:15:19 INFO - 'SHELL': '/bin/bash', 05:15:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6qSqrT1wbF/Listeners', 05:15:19 INFO - 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/', 05:15:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:15:19 INFO - 'USER': 'cltbld', 05:15:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:15:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:15:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:15:19 INFO - 'XPC_FLAGS': '0x0', 05:15:19 INFO - 'XPC_SERVICE_NAME': '0', 05:15:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:15:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:15:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:15:20 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-oYH2Ai-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:15:20 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)) 05:15:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:15:20 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-s9PSWt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:15:20 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)) 05:15:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:15:20 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-9Cvstq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:15:20 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)) 05:15:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:15:20 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-ET4jYS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:15:20 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)) 05:15:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:15:20 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-A_IDrd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:15:20 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)) 05:15:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:15:20 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-AoKSBn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:15:20 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)) 05:15:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:15:20 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-hYetRg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:15:20 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)) 05:15:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:15:20 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-9baG1O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:15:21 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)) 05:15:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:15:21 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-RiUzz8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:15:21 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)) 05:15:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:15:21 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-YWJSc7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:15:21 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)) 05:15:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:15:21 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-fAnmBz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:15:21 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)) 05:15:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:15:21 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-SGwZ4W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:15:21 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)) 05:15:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:15:21 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-WiPFz0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:15:21 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)) 05:15:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:15:21 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-gHcyBk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:15:21 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)) 05:15:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:15:21 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-fF7no4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:15:21 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)) 05:15:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:15:21 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-sedqLa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:15:21 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)) 05:15:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:15:21 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-fpgFtN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:15:21 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)) 05:15:21 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)) 05:15:21 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)) 05:15:21 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)) 05:15:21 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)) 05:15:21 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)) 05:15:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:15:21 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 05:15:21 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 05:15:21 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 05:15:21 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 05:15:21 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 05:15:21 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 05:15:25 INFO - Downloading blessings-1.6.tar.gz 05:15:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:15:25 INFO - Installing collected packages: blessings 05:15:25 INFO - Running setup.py install for blessings 05:15:25 INFO - Successfully installed blessings 05:15:25 INFO - Cleaning up... 05:15:25 INFO - Return code: 0 05:15:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:15:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:15:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:15:26 INFO - Reading from file tmpfile_stdout 05:15:26 INFO - Current package versions: 05:15:26 INFO - blessings == 1.6 05:15:26 INFO - blobuploader == 1.2.4 05:15:26 INFO - docopt == 0.6.1 05:15:26 INFO - manifestparser == 1.1 05:15:26 INFO - mozInstall == 1.12 05:15:26 INFO - mozcrash == 0.17 05:15:26 INFO - mozdebug == 0.1 05:15:26 INFO - mozdevice == 0.48 05:15:26 INFO - mozfile == 1.2 05:15:26 INFO - mozhttpd == 0.7 05:15:26 INFO - mozinfo == 0.9 05:15:26 INFO - mozleak == 0.1 05:15:26 INFO - mozlog == 3.1 05:15:26 INFO - moznetwork == 0.27 05:15:26 INFO - mozprocess == 0.22 05:15:26 INFO - mozprofile == 0.28 05:15:26 INFO - mozrunner == 6.11 05:15:26 INFO - mozscreenshot == 0.1 05:15:26 INFO - mozsystemmonitor == 0.0 05:15:26 INFO - moztest == 0.7 05:15:26 INFO - mozversion == 1.4 05:15:26 INFO - psutil == 3.1.1 05:15:26 INFO - requests == 1.2.3 05:15:26 INFO - wsgiref == 0.1.2 05:15:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:15: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')]} 05:15:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:15:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:15: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')]} 05:15:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:15:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:15:26 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 0x1077d1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077e1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ce94402f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107670e00>, '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 0x1074d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10766bb20>, '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.J3GLF0xqQJ/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.6qSqrT1wbF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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 05:15:26 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 05:15:26 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 05:15:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.J3GLF0xqQJ/Render', 05:15:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:15:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:15:26 INFO - 'HOME': '/Users/cltbld', 05:15:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:15:26 INFO - 'LOGNAME': 'cltbld', 05:15:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:15:26 INFO - 'MOZ_NO_REMOTE': '1', 05:15:26 INFO - 'NO_EM_RESTART': '1', 05:15:26 INFO - 'PAGER': '/bin/cat', 05:15:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:15:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:15:26 INFO - 'PWD': '/builds/slave/test', 05:15:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:15:26 INFO - 'SHELL': '/bin/bash', 05:15:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6qSqrT1wbF/Listeners', 05:15:26 INFO - 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/', 05:15:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:15:26 INFO - 'USER': 'cltbld', 05:15:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:15:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:15:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:15:26 INFO - 'XPC_FLAGS': '0x0', 05:15:26 INFO - 'XPC_SERVICE_NAME': '0', 05:15:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:15:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:15:26 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)) 05:15:26 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)) 05:15:26 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)) 05:15:26 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)) 05:15:26 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)) 05:15:26 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)) 05:15:26 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)) 05:15:26 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)) 05:15:26 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)) 05:15:26 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)) 05:15:26 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)) 05:15:26 INFO - Cleaning up... 05:15:26 INFO - Return code: 0 05:15:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:15: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')]} 05:15:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:15:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:15: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')]} 05:15:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:15:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:15:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1077d1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077e1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ce94402f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107670e00>, '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 0x1074d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10766bb20>, '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.J3GLF0xqQJ/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.6qSqrT1wbF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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 05:15:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:15:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:15:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.J3GLF0xqQJ/Render', 05:15:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:15:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:15:26 INFO - 'HOME': '/Users/cltbld', 05:15:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:15:26 INFO - 'LOGNAME': 'cltbld', 05:15:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:15:26 INFO - 'MOZ_NO_REMOTE': '1', 05:15:26 INFO - 'NO_EM_RESTART': '1', 05:15:26 INFO - 'PAGER': '/bin/cat', 05:15:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:15:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:15:26 INFO - 'PWD': '/builds/slave/test', 05:15:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:15:26 INFO - 'SHELL': '/bin/bash', 05:15:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6qSqrT1wbF/Listeners', 05:15:26 INFO - 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/', 05:15:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:15:26 INFO - 'USER': 'cltbld', 05:15:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:15:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:15:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:15:26 INFO - 'XPC_FLAGS': '0x0', 05:15:26 INFO - 'XPC_SERVICE_NAME': '0', 05:15:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:15:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:15:26 INFO - Downloading/unpacking jsonschema 05:15:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:15:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:15:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:15:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:15:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:15:29 INFO - Downloading/unpacking functools32 (from jsonschema) 05:15:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:15:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:15:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:15:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:15:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:15:29 INFO - Downloading functools32-3.2.3-2.tar.gz 05:15:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:15:29 INFO - warning: no files found matching '*.txt' 05:15:29 INFO - no previously-included directories found matching 'build' 05:15:29 INFO - no previously-included directories found matching 'dist' 05:15:29 INFO - no previously-included directories found matching '.git*' 05:15:29 INFO - Installing collected packages: jsonschema, functools32 05:15:29 INFO - Running setup.py install for functools32 05:15:29 INFO - warning: no files found matching '*.txt' 05:15:29 INFO - no previously-included directories found matching 'build' 05:15:29 INFO - no previously-included directories found matching 'dist' 05:15:29 INFO - no previously-included directories found matching '.git*' 05:15:29 INFO - Successfully installed jsonschema functools32 05:15:29 INFO - Cleaning up... 05:15:29 INFO - Return code: 0 05:15:29 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 05:15:29 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')]} 05:15:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:15:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:15:29 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')]} 05:15:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:15:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:15:29 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 0x1077d1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1077e1420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ce94402f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107670e00>, '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 0x1074d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10766bb20>, '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.J3GLF0xqQJ/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.6qSqrT1wbF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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 05:15:29 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 05:15:29 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 05:15:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.J3GLF0xqQJ/Render', 05:15:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:15:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:15:29 INFO - 'HOME': '/Users/cltbld', 05:15:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:15:29 INFO - 'LOGNAME': 'cltbld', 05:15:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:15:29 INFO - 'MOZ_NO_REMOTE': '1', 05:15:29 INFO - 'NO_EM_RESTART': '1', 05:15:29 INFO - 'PAGER': '/bin/cat', 05:15:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:15:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:15:29 INFO - 'PWD': '/builds/slave/test', 05:15:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:15:29 INFO - 'SHELL': '/bin/bash', 05:15:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6qSqrT1wbF/Listeners', 05:15:29 INFO - 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/', 05:15:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:15:29 INFO - 'USER': 'cltbld', 05:15:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:15:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:15:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:15:29 INFO - 'XPC_FLAGS': '0x0', 05:15:29 INFO - 'XPC_SERVICE_NAME': '0', 05:15:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:15:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:15:30 INFO - Downloading/unpacking flake8 05:15:30 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 05:15:30 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 05:15:30 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 05:15:30 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 05:15:30 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 05:15:30 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 05:15:32 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 05:15:32 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 05:15:32 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 05:15:32 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 05:15:32 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 05:15:32 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 05:15:32 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 05:15:32 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 05:15:33 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 05:15:33 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 05:15: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 05:15: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 05:15: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 05:15: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 05:15: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 05:15: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 05:15:33 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 05:15: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 05:15: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 05:15: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 05:15: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 05:15: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 05:15: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 05:15:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 05:15:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:15:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:15:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:15:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:15:34 INFO - no previously-included directories found matching 'docs/_build' 05:15:34 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 05:15:34 INFO - Running setup.py install for pep8 05:15:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 05:15:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 05:15:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 05:15:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 05:15:34 INFO - no previously-included directories found matching 'docs/_build' 05:15:34 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 05:15:34 INFO - Successfully installed flake8 mccabe pyflakes pep8 05:15:34 INFO - Cleaning up... 05:15:34 INFO - Return code: 0 05:15:34 INFO - Running post-action listener: _resource_record_post_action 05:15:34 INFO - Running post-action listener: _start_resource_monitoring 05:15:34 INFO - Starting resource monitoring. 05:15:34 INFO - ##### 05:15:34 INFO - ##### Running install step. 05:15:34 INFO - ##### 05:15:34 INFO - Running pre-action listener: _resource_record_pre_action 05:15:34 INFO - Running main action method: install 05:15:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:15:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:15:34 INFO - Reading from file tmpfile_stdout 05:15:34 INFO - Detecting whether we're running mozinstall >=1.0... 05:15:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:15:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:15:34 INFO - Reading from file tmpfile_stdout 05:15:34 INFO - Output received: 05:15:34 INFO - Usage: mozinstall [options] installer 05:15:34 INFO - Options: 05:15:34 INFO - -h, --help show this help message and exit 05:15:34 INFO - -d DEST, --destination=DEST 05:15:34 INFO - Directory to install application into. [default: 05:15:34 INFO - "/builds/slave/test"] 05:15:34 INFO - --app=APP Application being installed. [default: firefox] 05:15:34 INFO - mkdir: /builds/slave/test/build/application 05:15:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:15:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:16:08 INFO - Reading from file tmpfile_stdout 05:16:08 INFO - Output received: 05:16:08 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:16:08 INFO - Running post-action listener: _resource_record_post_action 05:16:08 INFO - ##### 05:16:08 INFO - ##### Running run-tests step. 05:16:08 INFO - ##### 05:16:08 INFO - Running pre-action listener: _resource_record_pre_action 05:16:08 INFO - Running main action method: run_tests 05:16:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:16:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:16:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:16:08 INFO - 2016-04-17 05:16:08.751 screenresolution[1655:9158] starting screenresolution argv=screenresolution get 05:16:08 INFO - 2016-04-17 05:16:08.754 screenresolution[1655:9158] Display 0: 1600x1200x32@60 05:16:08 INFO - 2016-04-17 05:16:08.759 screenresolution[1656:9161] starting screenresolution argv=screenresolution list 05:16:08 INFO - Available Modes on Display 0 05:16:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:16:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:16:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:16:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:16:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:16:08 INFO - Intel Iris: 05:16:08 INFO - Chipset Model: Intel Iris 05:16:08 INFO - Type: GPU 05:16:08 INFO - Bus: Built-In 05:16:08 INFO - VRAM (Dynamic, Max): 1536 MB 05:16:08 INFO - Vendor: Intel (0x8086) 05:16:08 INFO - Device ID: 0x0a2e 05:16:08 INFO - Revision ID: 0x0009 05:16:08 INFO - Displays: 05:16:08 INFO - Display: 05:16:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:16:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:16:08 INFO - Display Serial Number: 1600x1200 60 05:16:08 INFO - Main Display: Yes 05:16:08 INFO - Mirror: Off 05:16:08 INFO - Online: Yes 05:16:08 INFO - Rotation: Supported 05:16:08 INFO - Return code: 0 05:16:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:16:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:16:08 INFO - Python 2.7.3 05:16:08 INFO - Return code: 0 05:16:08 INFO - grabbing minidump binary from tooltool 05:16:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:16:08 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 0x107670e00>, '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 0x1074d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10766bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:16:09 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 05:16:09 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 05:16:09 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:16:09 INFO - Return code: 0 05:16:09 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:16:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:16:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:16:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:16:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:16:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 05:16:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 05:16:10 INFO - Return code: 0 05:16:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0053', '--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 05:16:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0053 --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 05:16:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.J3GLF0xqQJ/Render', 05:16:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:16:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:16:10 INFO - 'HOME': '/Users/cltbld', 05:16:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:16:10 INFO - 'LOGNAME': 'cltbld', 05:16:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:16:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:16:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:16:10 INFO - 'MOZ_NO_REMOTE': '1', 05:16:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:16:10 INFO - 'NO_EM_RESTART': '1', 05:16:10 INFO - 'PAGER': '/bin/cat', 05:16:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:16:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:16:10 INFO - 'PWD': '/builds/slave/test', 05:16:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:16:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:16:10 INFO - 'SHELL': '/bin/bash', 05:16:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6qSqrT1wbF/Listeners', 05:16:10 INFO - 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/', 05:16:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:16:10 INFO - 'USER': 'cltbld', 05:16:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:16:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:16:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:16:10 INFO - 'XPC_FLAGS': '0x0', 05:16:10 INFO - 'XPC_SERVICE_NAME': '0', 05:16:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:16:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0053', '--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 05:16:10 INFO - mozversion application_buildid: 20160417030601 05:16:10 INFO - mozversion application_changeset: 1da1937a9e03154ae7c60089f2dcf5ad9ee20fa3 05:16:10 INFO - mozversion application_display_name: Nightly 05:16:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:16:10 INFO - mozversion application_name: Firefox 05:16:10 INFO - mozversion application_remotingname: firefox 05:16:10 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:16:10 INFO - mozversion application_vendor: Mozilla 05:16:10 INFO - mozversion application_version: 48.0a1 05:16:10 INFO - mozversion platform_buildid: 20160417030601 05:16:10 INFO - mozversion platform_changeset: 1da1937a9e03154ae7c60089f2dcf5ad9ee20fa3 05:16:10 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:16:10 INFO - mozversion platform_version: 48.0a1 05:16:10 INFO - using testdate: 1460895370 05:16:10 INFO - actual date: 1460895370 05:16:10 INFO - starting webserver on 'localhost:49283' 05:16:10 INFO - SUITE-START | Running 2 tests 05:16:10 INFO - TEST-START | dromaeo_css 05:16:10 INFO - operating with platform_type : mac_ 05:16:10 INFO - Initialising browser for dromaeo_css test... 05:16:10 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/tmpp7bBna/profile http://localhost:49283/getInfo.html) 05:16:13 INFO - PROCESS | 1674 | JavaScript error: jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/omni.ja!/components/Weave.js, line 13: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIXPCComponents_Utils.import] 05:16:13 INFO - PROCESS | 1674 | __metrics Screen width/height:1600/1200 05:16:13 INFO - PROCESS | 1674 | colorDepth:24 05:16:13 INFO - PROCESS | 1674 | Browser inner width/height: 1024/689 05:16:13 INFO - PROCESS | 1674 | __metrics 05:16:13 ERROR - PROCESS | 1674 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 05:16:14 INFO - TEST-INFO | 1674: exit 0 05:16:14 INFO - Browser initialized. 05:16:14 INFO - Running cycle 1/1 for dromaeo_css test... 05:16:14 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/tmpp7bBna/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:16:26 INFO - PROCESS | 1679 | RSS: Main: 247701504 05:16:26 INFO - PROCESS | 1679 | 05:17:16 INFO - PROCESS | 1679 | 1460895436055 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] 05:19:27 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49283/tests/dromaeo/cssquery-ext.html) 05:19:28 INFO - PROCESS | 1679 | RSS: Main: 260472832 05:19:28 INFO - PROCESS | 1679 | 05:22:30 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-ext.html (next: http://localhost:49283/tests/dromaeo/cssquery-jquery.html) 05:22:31 INFO - PROCESS | 1679 | RSS: Main: 271831040 05:22:31 INFO - PROCESS | 1679 | 05:25:33 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49283/tests/dromaeo/cssquery-mootools.html) 05:25:33 INFO - PROCESS | 1679 | RSS: Main: 273727488 05:25:33 INFO - PROCESS | 1679 | 05:28:35 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49283/tests/dromaeo/cssquery-prototype.html) 05:28:36 INFO - PROCESS | 1679 | RSS: Main: 275263488 05:28:36 INFO - PROCESS | 1679 | 05:31:38 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49283/tests/dromaeo/cssquery-yui.html) 05:31:39 INFO - PROCESS | 1679 | RSS: Main: 278306816 05:31:39 INFO - PROCESS | 1679 | 05:34:41 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49283/tests/dromaeo/cssquery-yui.html (next: http://localhost:49283/tests/dromaeo/cssquery-dojo.html) 05:34:42 INFO - PROCESS | 1679 | RSS: Main: 279314432 05:34:42 INFO - PROCESS | 1679 | 05:34:42 INFO - PROCESS | 1679 | __start_tp_report 05:34:42 INFO - PROCESS | 1679 | _x_x_mozilla_page_load 05:34:42 INFO - PROCESS | 1679 | _x_x_mozilla_page_load_details 05:34:42 INFO - PROCESS | 1679 | |i|pagename|runs| 05:34:42 INFO - PROCESS | 1679 | |0;dojo.html;2841.5737639354093;3533.4523148911912;3554.573451185858;3554.6267641897616;3590.479380489829;5256.894862102756;5759.942400575993;5767.961766881965;5776.797812076578;5786.508146807527;5616.213730077792;5738.196652468648;5845.444682755142;5870.530357571395;5876.265466816648;5250.737463126838;5295.602829787762;5317;5330.680159190441;5384.461553844623;5252.658577192489;5414.1608050752;5438.83683489496;5446.15584584388;5483.259759932418;4329.935050974238;4819.662623616348;5172.353455818023;5365.946340536584;5401.27082843815;4362.629176519999;5241.554467870235;5294.258803767476;5314.97342513285;5343.412224655285;5259.921101183485;5262.947370526284;5273.657212281228;5318.946810531884;5327.413984461706;5590.636608620427;5651.463111004448;5738.7991420300505;5751.166080918285;5773.393793651648;5189.66267192635;6207.255129384503;6237.251529816405;6306.779262725782;6310.652914089723;5823.2138661280605;6060.818175454743;6266.436020758154;6319.58922670025;6379.425851673325;5697.857553561153;5834.883302333972;5912.556558258062;5912.704364781744;5934.43622855828;6275.6862156891975;6296.464800492009;6324.272708638474;6333.176687030748;6363.236411630634;6045.546584006217;6217.626942383471;6228.719707613168;6231.626102433868;6256.7810126645345;6171.938280617226;6230.221222347207;6270.2475702915945;6308;6314.778982735582;6112.724927378279;6128.846778830565;6232.875342493125;6257.405546473078;6263.091851681572;6139.26328840542;6180.81457556274;6233.657148856856;6282.623042617458;6299.118123462719;4187.5184353799095;4925.852224433273;4976.676516026447;4978.551930326288;5011.6241281904;4403.9779801100785;4417.867463976086;4423.62399196072;4459.6655250855665;4464.441944756905;5698.31620205578;5814.127880817827;5815.360310365856;5866.424189132484;5917.082852157922;6256.874862502816;6257.092721555349;6285.8742825143245;6313.05304204373;6322.810315690536;5811.244538210006;5834.281370936913;5862.501687356537;5879.764809407576;5884.264466941632;5345.518903298722;5420.07858664028;5450.3732070811575;5462.535684466785;5481.424450432727;6301.0233413820015;6315.179026726496;6326.525510586633;6339.429451349309;6372.012338087604;6313.936860631518;6319.842003949938;6326.6520341381665;6339.2392912850755;6361.109444677842;5318.521333080042;5360.544353729821;6022.698865056817;6230.7507699691505;6283.151774510383;5133.614978876674;6178.876422471615;6249.312575616769;6265.216847894013;6297.590656607306;6210.596311239755;6306.842328941813;6334.144890439732;6338.429541341301;6344.841378965378;5915.142304365929;6067.180930574493;6136.325004249618;6167.784127555514;6190.628562286277;4873.561379475864;6191.473724733448;6196.0705894116245;6206.565540412128;6231.40801623845;5980.790672326448;5988.6706231157705;6038.818835434944;6041.939580604314;6045.335013148638;6014.9097763534455;6139.785107521216;6146.494108943338;6175.413335733098;6183.876322473436;6093.268807743099;6098.329183789869;6101.786437474667;6115.663638499747;6139.6009259398 05:34:42 INFO - PROCESS | 1679 | |1;ext.html;516.220507034378;542.560525973961;561.200289587338;564.587850868866;627.1251604012402;37273;38127.66553170639;38161.61838381615;38162.61837381625;38211.42682859759;40649.79675101628;41542.961425964364;41546.05312495308;41594.792026039824;41690.54083107092;9546.28402869784;9713.902860971406;9771.657991970282;9803.362781419184;9812.901870981306;9587.185089267421;9672.56473458696;9683.951580242125;9693.903060969371;9717.222622190244;9656.372335798185;9670.129688328048;9684.08001239881;9746.951265243664;9748.610055597803;9628.566714497865;9651.227901767877;9664.420134791935;9690.515474226295;9691.370060946016;9609.279304052225;9630.27772917034;9678.612855485771;9684.467354295512;9708.90291097087;38155.664551740556;38212.42681359782;38451.807740961114;38557;38649.80675096607;2726.6046423268713;3082.3355388090627;3121.562981182636;3410.880619178317;3664.0839790052487;25893.223203303933;26558.804853781625;27052;27442;27512.449751004868;2643;2647.113217072283;2722.6460560127257;2758.8758505867286;2763.4196818668106;301925;303488;305098;306230;307575;31787.523187152212;32925.34149317048;35442.46836297458;36478.63521364806;36557.90326290215;34376.484352734726;34651.826740866636;34911.4763278551;35591.64408355884;35821.925342239774;14601.342939567745;14747.483838065617;14959.700805983819;14963;14977.251137443085;12131.33277669719;12207.023438124928;12306.876931230752;12593.559225427067;12750.97992160625;8012.959935200287;8127.796805079802;8130.918690813134;8259.132791056853;8340.040895296997;2998.3253767902484;3402.0474267205223;3459.7924124552605;3460.723142148622;3462.9480557791653;966.8356379415517;991.0188912976101;1060.7242117049623;1104.9779004419865;1106.4191299567792;29686.851565742032;29778;30108.397832043236;30554.84722576373;30557.541636875463;1070.2775626452114;1120.1542835159403;1140.5950887435008;1165.6153469355093;1193.7731830952264;15366.154861482726;15568.455104071303;15652.530424087296;15693.136877471621;15724;574800.2519974831;578979;589934.1006589965;590181;590250;60885.391146087975;61798;62289.68855155695;62529;62749;1453.324204245031;1459.292243262017;1479.0534058202543;1492.7611582147022;1494.2827442827504;29448.822047117876;29526.557101643062;29596;30377;30441;54025.72987135039;54348.456515434336;54701;55002.724986374815;55188.44811551833;465905;466072;468404.9739253846;469495;469514;23244.767552324938;26858.79135438949;27438;27777.166684999484;41926.79036604797;38816.64141755025;42797.14405711931;43078.49225277226;43854.34218486661;43962;25754.35614109662;30020.704961742853;36471.205496564646;38310.659126930426;40123.79938100408;35975.64024359723;37032.629673703996;37304.88085357444;37762;37833.05417364478;37335;37809.432858507695;39060.80469597634;39463.21073578455;39581.40627890641;26953.191404257905;30347.241318967204;30960.225994350323;32240.516392254605;32478.512822308156;20074.39776806698;24153.63769543423;24186.153484628656;24224.394390140387;24455.633165502175 05:34:42 INFO - PROCESS | 1679 | |2;jquery.html;335.27245876448103;375.97556158849653;388.37083924043037;396.2530629762897;405.12493015087415;17801.82198178018;20003.099860506274;20010.199592016303;21027.948602569886;21100.99999999998;11498.19512634114;11871.050315974722;12221.450034748435;12240.449179786909;12262.141650084497;5402.189671549269;5574.9721251393885;5608.915866262009;5610.8597285068;5615.887682246353;3957.8812635620834;5863.706814659271;6190.814275571717;6211.068339749051;6216.8445788855415;5922.200502932092;5991.453632206295;6185.59793613416;6187.1956645636;6205.9379406206035;5338.62629615927;6113.358097399798;6147.754089836401;6151.138840562325;6153.569250152514;4955.752212389366;5344.866378340534;5359.330083739533;5782.190493330937;5952.839196356712;10817.35095894241;12493;13000.544980925622;13070.215787052808;13193.604191874163;1975.2790231565518;2019.798020197983;2032.3699653107583;2099.286242677482;2163.578102270058;9327.037773359842;9330.906690933072;9382.296327775444;9431.339806213567;9612.615495380176;1662.5677323895752;1665.5586269638434;1719.9054052027134;1742.912854357277;1777.5022993561824;115291;118208.22687659692;118533;119286.46945004145;123044;11536.307821530734;11801.468933897995;11864.228825126513;11931.284122952477;11960.880391196151;14975.251237438084;15017.849821501864;15026.549203523911;15031.849681503263;15040.924795375953;7257.709691612276;8713.520756358334;8818.162274583905;8822.558872056372;8830.64677412909;8553.700620478254;8626.18051285127;8651.437656552303;8753.824923501494;8758.124187581318;4236.813692166198;4362.280223763044;4683.437987441529;4692.835750748707;4693.6949098308505;1814.854811615094;1978.851586131046;2006.277740013594;2021.2218295956163;2023.6559784836613;905.4838741917044;956.3401253135314;1028.598846449885;1038.8234000219982;1081.4376524207369;19791.40625781229;19967.800321996885;21593.136274549157;21813.23653672114;22107.447313817283;961.9374740641836;962.8363178259713;980.4362491567377;1014.4623349624711;1088.8747794003632;9959.751006224902;10819.242653014213;10977.560897564008;10978.396188209565;11031.338119712844;624244;631850;633597;638861;641982;132802.33598831945;135160;135347.32326338306;135852;142287;1066.7066556696816;1080.1574771678104;1082.32895604724;1094.3269889018197;1102.4763237462303;18345.08274586292;18427.72358414598;18478.353257635918;18537.721934171266;18552.350667726565;53954.46045539651;54103.64740881509;54194.72902635462;54673;54863;78659.82010269965;79655.60172199101;79726;80681;81276;7915.960420197862;8020.303046955857;8121.228483294078;8361.581920904053;8405.159484051546;9324.766880828034;11763.353015584225;12282.570110046105;12466.937665311616;12512.249265044127;8699.782030515606;8811.559422029;11057;11456.713582160513;11506.424678766196;10819.837702434312;10828.458577071273;10988;11006.724831879266;11497.54009839597;11268.774624507629;11317.71705707364;11406.828897566376;11463.713407164887;11544.538218471504;6088.908666369919;6703.396694297341;8068.47554908929;8095.676172953016;8263.586820659064;10180.796384072426;11303.717407064889;11321.320720756781;11432.771344573228;11619 05:34:42 INFO - PROCESS | 1679 | |3;mootools.html;1528.1060579560956;1595.4894433781187;1857.684193687073;1934.8452123830095;1961.1469010980225;8067.19328067193;8592.742217733463;8695.130486951302;8939.284857211433;8944.373893827435;8612.052674205848;8626.353023523245;8666.350023748213;8761.737147885558;8764.8685269721;2487.818286314;2507.360623041122;2520.7479252074827;2526.557852375836;2554.9361265968314;2448.951020979588;2501.1496091328945;2517.647529345893;2529.2285852814884;2532.556802559554;2500.612405077207;2511.3972646564876;2512.108201588437;2524.1544082732307;2525.6464095026713;2490.1284550407395;2499.4126380300704;2504.8121390895662;2506.949861002788;2520.395105174763;2099.653557163066;2459.360566252787;2485.0680994627014;2502.361897716089;2507.009731156183;8757.2994160467;8790.824183516293;8800.295976321879;8855.24730397917;8873.51195684237;5334.839954801362;5501.587380946445;5526.530244929146;5539.25220095286;5548.639338442988;3649.6715295623258;3690.372636651749;3698.42674385468;3732.618930995522;3734.7012239021083;1015.9542820573092;1038.190211634926;1039.4802598700649;1052.3159946035062;1076.682378698286;9372.390794598397;10566.577336906514;11684.591039313584;11782.823257651142;11848.526058957632;4776;4811.230203167476;4849.248366503198;4922.8769280768265;5019.347484826949;8710.390272680977;8975.519039358433;9950;9954.552045157985;10074.992500749866;5422.267993820863;5505.889882202414;5514.80698175562;5544.0575102232715;5558.194061861008;4491.034427598082;4884.291777692286;5047.1419858623385;5052.469490703424;5116.872078198075;4080.714349995472;4089.7750623715388;4100.7334523255895;4107.954375950913;4145.17096580679;1267.2903174222465;1316.1905428161613;1356.4709763192363;1378.8345398552037;1382.9930850345684;877.8376000439939;878.9384743068051;945.4138434170858;952.9428234305964;962.9277804164716;5728.198052272685;5795.507381872504;5988;6126.846828829315;6262.780802671976;441.56505841745854;462.736240343008;482.07922867323947;483.4440393547378;485.4174990012;4016.2770701273466;4047.3928910663635;4053.088055187618;4075.5924407559005;4103.753774773523;10442.2168337375;10836;10866.293690910066;10978.890211097947;10985.725356865982;10453.425061621683;10667.093297069681;10870.836937445945;10925.07136893373;10972.835407468894;8150.410669919427;8154.551499667576;8229.917700823034;8322.126176751475;8346;5869.765209391577;5992.340842507408;5998.580099393175;6064.211652485149;6087.726052327567;9769.16962058233;9898.158656514277;10018.048285412875;10059.949700251453;10240.64157754475;9991.050850169222;10152.883182849733;10183.49082545855;10299.897001029894;10352.223583231138;6831.6242606657115;7263.709451621876;7446.664900079401;7515.060617422822;7515.985341978763;8311;8352.039515455801;8397.916020839713;8407.663693452194;8426.705065322683;7273.490855640266;7420.183779784112;7423.406127509894;7513.661885215288;7566.697332106654;7300.342969132804;7338.266173382619;7344.302291282534;7424.851502969802;7442.516236444614;7000.754973576104;7034;7061;7070.823229419306;7114.075170227838;4856.053069651384;4870.0746858096845;4988.251762235694;5008.248762685481;5146.948530514647;3888.9222215556097;4911.557959783637;5014.047331007097;5076.314697515936;5176.870578235424 05:34:42 INFO - PROCESS | 1679 | |4;prototype.html;296.3909166662509;317.985690643921;326.5200155771017;328.13209062030944;332.91344250494865;4763.666543341968;6073.9392606073925;6152.723127459264;6173.228346456693;6179.969100154499;5999.490043346321;6039.547033972448;6048.2742070951435;6053.33413324535;6058.303295121052;3657.7988210648405;4274.743515389071;4361.498427680825;4479.10417916418;4513.458384993789;3593.1915319053264;4488.214562451574;4505.301678239878;4540.18276710192;4554.043650833329;3648.296978566757;3753.887383378503;4475.687990886753;4480.103979204156;4510.436754963591;3642.7978767006825;4368.728261304104;4460.5316441773475;4463.531329210418;4487.708298960573;3801.3727734923705;3813.733038687293;3832.367659336206;3837.6184573553496;3850.3839385698434;4547.840825571089;4706.5293470653005;5739.483446489836;5742.569307301927;5769.769209231626;4542.977829988259;4552.658550608718;4878.243872199815;5166.4575219601775;5167.638265321429;5799.811038737049;6045.758169673208;6248;6344.746210151635;6388.680565971683;3887.9805600971813;4202.9789851050855;4205.936910946339;4207.516135644379;4245.575442455761;5520.834374968757;6146.231721034871;6699.832504187386;6735.764248251287;6744.865102697919;3549.414346632777;3787.905302367463;3993.600639935983;4184.979075104666;4277.251480990814;3953.426753120839;4082.918026722921;4333.71830830995;4336.631386332134;4353.847615333448;4901.43633482147;4905.828296009622;4919.877003074952;5084.898302033938;5098.107831129538;5610.747516361774;5862.325832529314;5911.438413350811;6352.618842869442;6442.130312407859;1278.878506541877;1427.200767570164;1485.4578079001087;1488.1889370293216;1491.4779827060443;939.7086903060073;962.129273007929;970.9465979371062;970.9902900971041;972.6741541583507;5935.287765468085;5977.282726072899;5980;5987.2815262168815;6000.399960003964;6635.834104147339;6649.105005073531;6658.400743933069;6662.800115996528;6687.76592819249;5694.402087780806;5998.130271110752;6066.27111273285;6077.24034495688;6117.694115294217;2809.4802461544677;2957.6450825900597;3463.497792820027;3514.4903988921465;3567.678908898212;5877.706114694248;6470;6972;7002.649867506604;7003.089598352181;5707.857303567444;5747.597668163272;6948.895766563505;6949.548279361826;6984.092068031225;6767.932320676829;6979.30206979298;7028.297170283033;7063.223045464994;7070.080889484334;6474.384933431316;6670.199576050905;6706.6981985811735;6711.697973591297;6729.461643068641;5796.94203057964;6368.172137622079;6603.042558829039;6648;6672.766453174309;5221.817236396708;6961.89557156633;7005.0578756921195;7012.649367531501;7026.962180967462;4001.339778936443;4089.6728261739586;4270.893227669333;4292.034292284211;4310.43964284641;5393.541548968382;6552;6642.634655093823;6650.9667451662435;6701.615684079508;5725.370209276893;5926.111083337361;5928.110783382527;6317.6209427434505;6461.483081353386;4986.80052797884;6133.815985520441;6430.260520040255;6469.547131700736;6813.7955861324235;5290.285811415563;6241.282252541016;6285.779997700059;6294.545127400409;6307.432331090224;5805.76776928935;6023.186869772525;6384.010478375859;6441.871162576816;6476.481881449567;5545.833624991256;5985.491233245223;6268.091126786499;6350.555461117678;6363.96818015907 05:34:42 INFO - PROCESS | 1679 | |5;yui.html;665.477600083934;724.4494184419846;749.6364263332283;753.1940823319047;779.1001393390633;14980.850191498082;15114.773278400833;15265.542033738979;15303;15526.922365388158;11041.779164416706;13888.23614701191;14174.220417877013;14222.359993800277;14477.493287734931;899.2850683706437;914.095045904553;917.101240784032;919.2829592917535;919.8160367926408;723.2550473012806;883.3507372081515;900.0234745301323;912.8219997100568;920.1028996728203;961.9519024048805;963.1620490173559;964.5273815830262;965.1217392173124;967.3905439573094;936.4053825820653;957.8850537935423;957.9712608621752;958.9616415343413;961.4904100826573;693.0747452151384;709;711.2105562825311;714.1144980224536;715.6529083394549;14901.627459313495;15182.772258416133;15190.468333608382;15258.771118433233;15266.542003739905;12705;12720.681982950407;12722.745545089047;12738.872611273953;12960.092793504458;10067.597296108146;10131.746706332327;10135.493225338703;10338.379697218115;10469.68590942273;2858.5569236768333;3260.9890933810593;3274.001429563982;3509.350770107538;3541.061618671054;70792.64603677;84241.57879210626;85776.99999999937;86906;88262;6289.811305660837;7688.654010569538;7701.422393320523;7705.807354816062;7723.382129429632;21726;21758.238461653767;21768.673469897964;21770.564588708137;21771.129154833947;5773.6824474654295;7257.0565826442225;7388.187299397062;7555.962220188864;7635.350995165362;4040.2323558523785;4060.5540475810835;4066.0851308455362;4068.6745060395097;4083.34666453372;3545.8758943436856;3589.1565482111682;3591.371509985794;3615.94576081359;3624.655657712513;626.9341719119518;640.8173670503884;646.3213625693004;648.7340190521863;649.078308801503;553.3995614757952;582.2256398989334;590.9854881331895;592.5378205000106;604.8125081224835;6436.452901503331;7603;7697.461177717618;7822.726204582812;8044.074931382966;511.37867490998616;516.1947362115037;542.7937383794133;543.2801537962137;586.9941300587025;3780.8487660493824;3829.7319187657154;3847.2690188864026;3862.7102967277565;3883.514560679915;92170.61744073844;93589;94015;94172;96080.51959740157;78261;78318.60840695874;78766;78869.60565197137;79529.60235198903;66746.66626666836;67691.98462023074;69041.61916761637;69808.90573282809;70494.64752676204;7230.602316872622;7377.114746230487;7603.353714934293;7655.349295309961;7671.347935425551;36437.36031878519;42584;42636.78681606572;43729;43745.562544375425;53814.654633632905;56429;65126.67436662786;65585.67207164124;66530;9031.729048128567;10984.615538456115;11032.779344413228;11060.723481913017;11193.552257909918;10393.908639593037;11918.344491052729;11978.880211197777;12351.19717218378;12527.18573292733;10536.683899483029;10651.455538946964;11269.887301126884;11547.422628868691;11756.529738810352;8857.911420885708;11253.941064025288;11413.828792568283;11443;12577.119601627799;10574.524146413276;11086.667399978014;11340.376279304717;11467;11790.469428875893;9558.474283914451;9596.376235544667;9860.30977831545;9965.950170249102;10253.436061016417;9026.368154229142;9603.183729383081;9716.125548700651;9745.853812192681;9814.2148628111 05:34:42 INFO - PROCESS | 1679 | __end_tp_report 05:34:42 INFO - PROCESS | 1679 | __start_cc_report 05:34:42 INFO - PROCESS | 1679 | _x_x_mozilla_cycle_collect,406 05:34:42 INFO - PROCESS | 1679 | __end_cc_report 05:34:42 INFO - PROCESS | 1679 | __startTimestamp1460896482118__endTimestamp 05:34:42 INFO - PROCESS | 1679 | 05:34:42 INFO - PROCESS | 1679 | ------- Summary: start ------- 05:34:42 INFO - PROCESS | 1679 | Number of tests: 6 05:34:42 INFO - PROCESS | 1679 | 05:34:42 INFO - PROCESS | 1679 | [#0] dojo.html Cycles:165 Average:5750.72 Median:5980.79 stddev:664.70 (11.1%) stddev-sans-first:626.34 05:34:42 INFO - PROCESS | 1679 | Values: 2841.6 3533.5 3554.6 3554.6 3590.5 5256.9 5759.9 5768.0 5776.8 5786.5 5616.2 5738.2 5845.4 5870.5 5876.3 5250.7 5295.6 5317.0 5330.7 5384.5 5252.7 5414.2 5438.8 5446.2 5483.3 4329.9 4819.7 5172.4 5365.9 5401.3 4362.6 5241.6 5294.3 5315.0 5343.4 5259.9 5262.9 5273.7 5318.9 5327.4 5590.6 5651.5 5738.8 5751.2 5773.4 5189.7 6207.3 6237.3 6306.8 6310.7 5823.2 6060.8 6266.4 6319.6 6379.4 5697.9 5834.9 5912.6 5912.7 5934.4 6275.7 6296.5 6324.3 6333.2 6363.2 6045.5 6217.6 6228.7 6231.6 6256.8 6171.9 6230.2 6270.2 6308.0 6314.8 6112.7 6128.8 6232.9 6257.4 6263.1 6139.3 6180.8 6233.7 6282.6 6299.1 4187.5 4925.9 4976.7 4978.6 5011.6 4404.0 4417.9 4423.6 4459.7 4464.4 5698.3 5814.1 5815.4 5866.4 5917.1 6256.9 6257.1 6285.9 6313.1 6322.8 5811.2 5834.3 5862.5 5879.8 5884.3 5345.5 5420.1 5450.4 5462.5 5481.4 6301.0 6315.2 6326.5 6339.4 6372.0 6313.9 6319.8 6326.7 6339.2 6361.1 5318.5 5360.5 6022.7 6230.8 6283.2 5133.6 6178.9 6249.3 6265.2 6297.6 6210.6 6306.8 6334.1 6338.4 6344.8 5915.1 6067.2 6136.3 6167.8 6190.6 4873.6 6191.5 6196.1 6206.6 6231.4 5980.8 5988.7 6038.8 6041.9 6045.3 6014.9 6139.8 6146.5 6175.4 6183.9 6093.3 6098.3 6101.8 6115.7 6139.6 05:34:42 INFO - PROCESS | 1679 | 05:34:42 INFO - PROCESS | 1679 | [#1] ext.html Cycles:180 Average:58265.54 Median:20348.36 stddev:124802.64 (613.3%) stddev-sans-first:125077.42 05:34:42 INFO - PROCESS | 1679 | Values: 516.2 542.6 561.2 564.6 627.1 37273.0 38127.7 38161.6 38162.6 38211.4 40649.8 41543.0 41546.1 41594.8 41690.5 9546.3 9713.9 9771.7 9803.4 9812.9 9587.2 9672.6 9684.0 9693.9 9717.2 9656.4 9670.1 9684.1 9747.0 9748.6 9628.6 9651.2 9664.4 9690.5 9691.4 9609.3 9630.3 9678.6 9684.5 9708.9 38155.7 38212.4 38451.8 38557.0 38649.8 2726.6 3082.3 3121.6 3410.9 3664.1 25893.2 26558.8 27052.0 27442.0 27512.4 2643.0 2647.1 2722.6 2758.9 2763.4 301925.0 303488.0 305098.0 306230.0 307575.0 31787.5 32925.3 35442.5 36478.6 36557.9 34376.5 34651.8 34911.5 35591.6 35821.9 14601.3 14747.5 14959.7 14963.0 14977.3 12131.3 12207.0 12306.9 12593.6 12751.0 8013.0 8127.8 8130.9 8259.1 8340.0 2998.3 3402.0 3459.8 3460.7 3462.9 966.8 991.0 1060.7 1105.0 1106.4 29686.9 29778.0 30108.4 30554.8 30557.5 1070.3 1120.2 1140.6 1165.6 1193.8 15366.2 15568.5 15652.5 15693.1 15724.0 574800.3 578979.0 589934.1 590181.0 590250.0 60885.4 61798.0 62289.7 62529.0 62749.0 1453.3 1459.3 1479.1 1492.8 1494.3 29448.8 29526.6 29596.0 30377.0 30441.0 54025.7 54348.5 54701.0 55002.7 55188.4 465905.0 466072.0 468405.0 469495.0 469514.0 23244.8 26858.8 27438.0 27777.2 41926.8 38816.6 42797.1 43078.5 43854.3 43962.0 25754.4 30020.7 36471.2 38310.7 40123.8 35975.6 37032.6 37304.9 37762.0 37833.1 37335.0 37809.4 39060.8 39463.2 39581.4 26953.2 30347.2 30960.2 32240.5 32478.5 20074.4 24153.6 24186.2 24224.4 24455.6 05:34:42 INFO - PROCESS | 1679 | 05:34:42 INFO - PROCESS | 1679 | [#2] jquery.html Cycles:180 Average:35710.40 Median:21064.47 stddev:105894.83 (502.7%) stddev-sans-first:106158.58 05:34:42 INFO - PROCESS | 1679 | Values: 335.3 376.0 388.4 396.3 405.1 17801.8 20003.1 20010.2 21027.9 21101.0 11498.2 11871.1 12221.5 12240.4 12262.1 5402.2 5575.0 5608.9 5610.9 5615.9 3957.9 5863.7 6190.8 6211.1 6216.8 5922.2 5991.5 6185.6 6187.2 6205.9 5338.6 6113.4 6147.8 6151.1 6153.6 4955.8 5344.9 5359.3 5782.2 5952.8 10817.4 12493.0 13000.5 13070.2 13193.6 1975.3 2019.8 2032.4 2099.3 2163.6 9327.0 9330.9 9382.3 9431.3 9612.6 1662.6 1665.6 1719.9 1742.9 1777.5 115291.0 118208.2 118533.0 119286.5 123044.0 11536.3 11801.5 11864.2 11931.3 11960.9 14975.3 15017.8 15026.5 15031.8 15040.9 7257.7 8713.5 8818.2 8822.6 8830.6 8553.7 8626.2 8651.4 8753.8 8758.1 4236.8 4362.3 4683.4 4692.8 4693.7 1814.9 1978.9 2006.3 2021.2 2023.7 905.5 956.3 1028.6 1038.8 1081.4 19791.4 19967.8 21593.1 21813.2 22107.4 961.9 962.8 980.4 1014.5 1088.9 9959.8 10819.2 10977.6 10978.4 11031.3 624244.0 631850.0 633597.0 638861.0 641982.0 132802.3 135160.0 135347.3 135852.0 142287.0 1066.7 1080.2 1082.3 1094.3 1102.5 18345.1 18427.7 18478.4 18537.7 18552.4 53954.5 54103.6 54194.7 54673.0 54863.0 78659.8 79655.6 79726.0 80681.0 81276.0 7916.0 8020.3 8121.2 8361.6 8405.2 9324.8 11763.4 12282.6 12466.9 12512.2 8699.8 8811.6 11057.0 11456.7 11506.4 10819.8 10828.5 10988.0 11006.7 11497.5 11268.8 11317.7 11406.8 11463.7 11544.5 6088.9 6703.4 8068.5 8095.7 8263.6 10180.8 11303.7 11321.3 11432.8 11619.0 05:34:42 INFO - PROCESS | 1679 | 05:34:42 INFO - PROCESS | 1679 | [#3] mootools.html Cycles:180 Average:5743.48 Median:4917.22 stddev:3157.67 (64.2%) stddev-sans-first:3150.64 05:34:42 INFO - PROCESS | 1679 | Values: 1528.1 1595.5 1857.7 1934.8 1961.1 8067.2 8592.7 8695.1 8939.3 8944.4 8612.1 8626.4 8666.4 8761.7 8764.9 2487.8 2507.4 2520.7 2526.6 2554.9 2449.0 2501.1 2517.6 2529.2 2532.6 2500.6 2511.4 2512.1 2524.2 2525.6 2490.1 2499.4 2504.8 2506.9 2520.4 2099.7 2459.4 2485.1 2502.4 2507.0 8757.3 8790.8 8800.3 8855.2 8873.5 5334.8 5501.6 5526.5 5539.3 5548.6 3649.7 3690.4 3698.4 3732.6 3734.7 1016.0 1038.2 1039.5 1052.3 1076.7 9372.4 10566.6 11684.6 11782.8 11848.5 4776.0 4811.2 4849.2 4922.9 5019.3 8710.4 8975.5 9950.0 9954.6 10075.0 5422.3 5505.9 5514.8 5544.1 5558.2 4491.0 4884.3 5047.1 5052.5 5116.9 4080.7 4089.8 4100.7 4108.0 4145.2 1267.3 1316.2 1356.5 1378.8 1383.0 877.8 878.9 945.4 952.9 962.9 5728.2 5795.5 5988.0 6126.8 6262.8 441.6 462.7 482.1 483.4 485.4 4016.3 4047.4 4053.1 4075.6 4103.8 10442.2 10836.0 10866.3 10978.9 10985.7 10453.4 10667.1 10870.8 10925.1 10972.8 8150.4 8154.6 8229.9 8322.1 8346.0 5869.8 5992.3 5998.6 6064.2 6087.7 9769.2 9898.2 10018.0 10059.9 10240.6 9991.1 10152.9 10183.5 10299.9 10352.2 6831.6 7263.7 7446.7 7515.1 7516.0 8311.0 8352.0 8397.9 8407.7 8426.7 7273.5 7420.2 7423.4 7513.7 7566.7 7300.3 7338.3 7344.3 7424.9 7442.5 7000.8 7034.0 7061.0 7070.8 7114.1 4856.1 4870.1 4988.3 5008.2 5146.9 3888.9 4911.6 5014.0 5076.3 5176.9 05:34:42 INFO - PROCESS | 1679 | 05:34:42 INFO - PROCESS | 1679 | [#4] prototype.html Cycles:180 Average:5124.97 Median:5870.02 stddev:1668.52 (28.4%) stddev-sans-first:1633.37 05:34:42 INFO - PROCESS | 1679 | Values: 296.4 318.0 326.5 328.1 332.9 4763.7 6073.9 6152.7 6173.2 6180.0 5999.5 6039.5 6048.3 6053.3 6058.3 3657.8 4274.7 4361.5 4479.1 4513.5 3593.2 4488.2 4505.3 4540.2 4554.0 3648.3 3753.9 4475.7 4480.1 4510.4 3642.8 4368.7 4460.5 4463.5 4487.7 3801.4 3813.7 3832.4 3837.6 3850.4 4547.8 4706.5 5739.5 5742.6 5769.8 4543.0 4552.7 4878.2 5166.5 5167.6 5799.8 6045.8 6248.0 6344.7 6388.7 3888.0 4203.0 4205.9 4207.5 4245.6 5520.8 6146.2 6699.8 6735.8 6744.9 3549.4 3787.9 3993.6 4185.0 4277.3 3953.4 4082.9 4333.7 4336.6 4353.8 4901.4 4905.8 4919.9 5084.9 5098.1 5610.7 5862.3 5911.4 6352.6 6442.1 1278.9 1427.2 1485.5 1488.2 1491.5 939.7 962.1 970.9 971.0 972.7 5935.3 5977.3 5980.0 5987.3 6000.4 6635.8 6649.1 6658.4 6662.8 6687.8 5694.4 5998.1 6066.3 6077.2 6117.7 2809.5 2957.6 3463.5 3514.5 3567.7 5877.7 6470.0 6972.0 7002.6 7003.1 5707.9 5747.6 6948.9 6949.5 6984.1 6767.9 6979.3 7028.3 7063.2 7070.1 6474.4 6670.2 6706.7 6711.7 6729.5 5796.9 6368.2 6603.0 6648.0 6672.8 5221.8 6961.9 7005.1 7012.6 7027.0 4001.3 4089.7 4270.9 4292.0 4310.4 5393.5 6552.0 6642.6 6651.0 6701.6 5725.4 5926.1 5928.1 6317.6 6461.5 4986.8 6133.8 6430.3 6469.5 6813.8 5290.3 6241.3 6285.8 6294.5 6307.4 5805.8 6023.2 6384.0 6441.9 6476.5 5545.8 5985.5 6268.1 6350.6 6364.0 05:34:42 INFO - PROCESS | 1679 | 05:34:42 INFO - PROCESS | 1679 | [#5] yui.html Cycles:180 Average:17873.69 Median:598.68 stddev:25504.22 (4260.1%) stddev-sans-first:25543.03 05:34:42 INFO - PROCESS | 1679 | Values: 665.5 724.4 749.6 753.2 779.1 14980.9 15114.8 15265.5 15303.0 15526.9 11041.8 13888.2 14174.2 14222.4 14477.5 899.3 914.1 917.1 919.3 919.8 723.3 883.4 900.0 912.8 920.1 962.0 963.2 964.5 965.1 967.4 936.4 957.9 958.0 959.0 961.5 693.1 709.0 711.2 714.1 715.7 14901.6 15182.8 15190.5 15258.8 15266.5 12705.0 12720.7 12722.7 12738.9 12960.1 10067.6 10131.7 10135.5 10338.4 10469.7 2858.6 3261.0 3274.0 3509.4 3541.1 70792.6 84241.6 85777.0 86906.0 88262.0 6289.8 7688.7 7701.4 7705.8 7723.4 21726.0 21758.2 21768.7 21770.6 21771.1 5773.7 7257.1 7388.2 7556.0 7635.4 4040.2 4060.6 4066.1 4068.7 4083.3 3545.9 3589.2 3591.4 3615.9 3624.7 626.9 640.8 646.3 648.7 649.1 553.4 582.2 591.0 592.5 604.8 6436.5 7603.0 7697.5 7822.7 8044.1 511.4 516.2 542.8 543.3 587.0 3780.8 3829.7 3847.3 3862.7 3883.5 92170.6 93589.0 94015.0 94172.0 96080.5 78261.0 78318.6 78766.0 78869.6 79529.6 66746.7 67692.0 69041.6 69808.9 70494.6 7230.6 7377.1 7603.4 7655.3 7671.3 36437.4 42584.0 42636.8 43729.0 43745.6 53814.7 56429.0 65126.7 65585.7 66530.0 9031.7 10984.6 11032.8 11060.7 11193.6 10393.9 11918.3 11978.9 12351.2 12527.2 10536.7 10651.5 11269.9 11547.4 11756.5 8857.9 11253.9 11413.8 11443.0 12577.1 10574.5 11086.7 11340.4 11467.0 11790.5 9558.5 9596.4 9860.3 9966.0 10253.4 9026.4 9603.2 9716.1 9745.9 9814.2 05:34:42 INFO - PROCESS | 1679 | -------- Summary: end -------- 05:34:42 INFO - PROCESS | 1679 | 05:34:42 INFO - TEST-INFO | 1679: exit 0 05:34:42 INFO - TEST-OK | dromaeo_css | took 1112196ms 05:34:42 INFO - TEST-START | kraken 05:34:42 INFO - operating with platform_type : mac_ 05:34:42 INFO - Initialising browser for kraken test... 05:34:42 INFO - TEST-INFO | started process 1687 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/tmpBHBrAs/profile http://localhost:49283/getInfo.html) 05:34:44 INFO - PROCESS | 1687 | JavaScript error: jar:file:///builds/slave/test/build/application/FirefoxNightly.app/Contents/Resources/omni.ja!/components/Weave.js, line 13: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIXPCComponents_Utils.import] 05:34:45 INFO - PROCESS | 1687 | __metrics Screen width/height:1600/1200 05:34:45 INFO - PROCESS | 1687 | colorDepth:24 05:34:45 INFO - PROCESS | 1687 | Browser inner width/height: 1024/689 05:34:45 INFO - PROCESS | 1687 | __metrics 05:34:45 ERROR - PROCESS | 1687 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 05:34:46 INFO - TEST-INFO | 1687: exit 0 05:34:46 INFO - Browser initialized. 05:34:46 INFO - Running cycle 1/1 for kraken test... 05:34:46 INFO - TEST-INFO | started process 1691 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/tmpBHBrAs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:34:58 INFO - PROCESS | 1691 | RSS: Main: 247767040 05:34:58 INFO - PROCESS | 1691 | 05:35:01 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-beat-detection) 05:35:02 INFO - PROCESS | 1691 | RSS: Main: 260042752 05:35:02 INFO - PROCESS | 1691 | 05:35:04 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-dft) 05:35:05 INFO - PROCESS | 1691 | RSS: Main: 269467648 05:35:05 INFO - PROCESS | 1691 | 05:35:08 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-fft) 05:35:09 INFO - PROCESS | 1691 | RSS: Main: 272199680 05:35:09 INFO - PROCESS | 1691 | 05:35:11 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49283/tests/kraken/driver.html?testName=audio-oscillator) 05:35:12 INFO - PROCESS | 1691 | RSS: Main: 274206720 05:35:12 INFO - PROCESS | 1691 | 05:35:14 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49283/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:35:15 INFO - PROCESS | 1691 | RSS: Main: 275267584 05:35:15 INFO - PROCESS | 1691 | 05:35:18 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49283/tests/kraken/driver.html?testName=imaging-darkroom) 05:35:18 INFO - PROCESS | 1691 | RSS: Main: 274767872 05:35:18 INFO - PROCESS | 1691 | 05:35:21 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49283/tests/kraken/driver.html?testName=imaging-desaturate) 05:35:22 INFO - PROCESS | 1691 | RSS: Main: 274829312 05:35:22 INFO - PROCESS | 1691 | 05:35:25 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49283/tests/kraken/driver.html?testName=json-parse-financial) 05:35:25 INFO - PROCESS | 1691 | RSS: Main: 274849792 05:35:25 INFO - PROCESS | 1691 | 05:35:27 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49283/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:35:28 INFO - PROCESS | 1691 | RSS: Main: 274767872 05:35:28 INFO - PROCESS | 1691 | 05:35:30 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:35:30 INFO - PROCESS | 1691 | RSS: Main: 274808832 05:35:30 INFO - PROCESS | 1691 | 05:35:32 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:35:33 INFO - PROCESS | 1691 | RSS: Main: 275144704 05:35:33 INFO - PROCESS | 1691 | 05:35:36 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:35:37 INFO - PROCESS | 1691 | RSS: Main: 275206144 05:35:37 INFO - PROCESS | 1691 | 05:35:40 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:35:40 INFO - PROCESS | 1691 | RSS: Main: 275177472 05:35:40 INFO - PROCESS | 1691 | 05:35:42 INFO - PROCESS | 1691 | Cycle 1(1): loaded http://localhost:49283/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49283/tests/kraken/driver.html?testName=ai-astar) 05:35:42 INFO - PROCESS | 1691 | RSS: Main: 276680704 05:35:42 INFO - PROCESS | 1691 | 05:35:42 INFO - PROCESS | 1691 | __start_tp_report 05:35:42 INFO - PROCESS | 1691 | _x_x_mozilla_page_load 05:35:42 INFO - PROCESS | 1691 | _x_x_mozilla_page_load_details 05:35:42 INFO - PROCESS | 1691 | |i|pagename|runs| 05:35:42 INFO - PROCESS | 1691 | |0;ai-astar;115;109;146;175;145;134;159;116;116;116 05:35:42 INFO - PROCESS | 1691 | |1;audio-beat-detection;192;122;201;206;153;145;141;124;169;123 05:35:42 INFO - PROCESS | 1691 | |2;audio-dft;195;204;272;278;201;223;196;193;201;199 05:35:42 INFO - PROCESS | 1691 | |3;audio-fft;83;110;65;135;71;82;92;62;87;84 05:35:42 INFO - PROCESS | 1691 | |4;audio-oscillator;79;85;133;76;83;87;153;122;117;152 05:35:42 INFO - PROCESS | 1691 | |5;imaging-gaussian-blur;137;130;91;88;90;106;134;88;92;86 05:35:42 INFO - PROCESS | 1691 | |6;imaging-darkroom;96;96;96;105;95;96;150;96;94;95 05:35:42 INFO - PROCESS | 1691 | |7;imaging-desaturate;91;109;90;91;93;87;144;143;119;94 05:35:42 INFO - PROCESS | 1691 | |8;json-parse-financial;62;62;61;62;61;61;63;77;65;61 05:35:42 INFO - PROCESS | 1691 | |9;json-stringify-tinderbox;68;67;74;65;83;108;102;60;84;102 05:35:42 INFO - PROCESS | 1691 | |10;stanford-crypto-aes;83;85;84;81;84;82;106;112;111;110 05:35:42 INFO - PROCESS | 1691 | |11;stanford-crypto-ccm;140;143;145;148;150;135;134;134;166;176 05:35:42 INFO - PROCESS | 1691 | |12;stanford-crypto-pbkdf2;170;173;172;176;164;173;173;173;174;175 05:35:42 INFO - PROCESS | 1691 | |13;stanford-crypto-sha256-iterative;69;70;70;58;57;82;96;102;98;101 05:35:42 INFO - PROCESS | 1691 | __end_tp_report 05:35:42 INFO - PROCESS | 1691 | __start_cc_report 05:35:42 INFO - PROCESS | 1691 | _x_x_mozilla_cycle_collect,1039 05:35:42 INFO - PROCESS | 1691 | __end_cc_report 05:35:42 INFO - PROCESS | 1691 | __startTimestamp1460896542756__endTimestamp 05:35:42 INFO - PROCESS | 1691 | 05:35:42 INFO - PROCESS | 1691 | ------- Summary: start ------- 05:35:42 INFO - PROCESS | 1691 | Number of tests: 14 05:35:42 INFO - PROCESS | 1691 | 05:35:42 INFO - PROCESS | 1691 | [#0] ai-astar Cycles:10 Average:133.10 Median:139.50 stddev:22.41 (16.1%) stddev-sans-first:22.79 05:35:42 INFO - PROCESS | 1691 | Values: 115.0 109.0 146.0 175.0 145.0 134.0 159.0 116.0 116.0 116.0 05:35:42 INFO - PROCESS | 1691 | 05:35:42 INFO - PROCESS | 1691 | [#1] audio-beat-detection Cycles:10 Average:157.60 Median:161.00 stddev:32.64 (20.3%) stddev-sans-first:32.16 05:35:42 INFO - PROCESS | 1691 | Values: 192.0 122.0 201.0 206.0 153.0 145.0 141.0 124.0 169.0 123.0 05:35:42 INFO - PROCESS | 1691 | 05:35:42 INFO - PROCESS | 1691 | [#2] audio-dft Cycles:10 Average:216.20 Median:202.50 stddev:32.11 (15.9%) stddev-sans-first:33.13 05:35:42 INFO - PROCESS | 1691 | Values: 195.0 204.0 272.0 278.0 201.0 223.0 196.0 193.0 201.0 199.0 05:35:42 INFO - PROCESS | 1691 | 05:35:42 INFO - PROCESS | 1691 | [#3] audio-fft Cycles:10 Average:87.10 Median:82.50 stddev:21.79 (26.4%) stddev-sans-first:23.06 05:35:42 INFO - PROCESS | 1691 | Values: 83.0 110.0 65.0 135.0 71.0 82.0 92.0 62.0 87.0 84.0 05:35:42 INFO - PROCESS | 1691 | 05:35:42 INFO - PROCESS | 1691 | [#4] audio-oscillator Cycles:10 Average:108.70 Median:77.50 stddev:30.40 (39.2%) stddev-sans-first:30.29 05:35:42 INFO - PROCESS | 1691 | Values: 79.0 85.0 133.0 76.0 83.0 87.0 153.0 122.0 117.0 152.0 05:35:42 INFO - PROCESS | 1691 | 05:35:42 INFO - PROCESS | 1691 | [#5] imaging-gaussian-blur Cycles:10 Average:104.20 Median:88.00 stddev:21.12 (24.0%) stddev-sans-first:18.77 05:35:42 INFO - PROCESS | 1691 | Values: 137.0 130.0 91.0 88.0 90.0 106.0 134.0 88.0 92.0 86.0 05:35:42 INFO - PROCESS | 1691 | 05:35:42 INFO - PROCESS | 1691 | [#6] imaging-darkroom Cycles:10 Average:101.90 Median:96.00 stddev:17.18 (17.9%) stddev-sans-first:18.08 05:35:42 INFO - PROCESS | 1691 | Values: 96.0 96.0 96.0 105.0 95.0 96.0 150.0 96.0 94.0 95.0 05:35:42 INFO - PROCESS | 1691 | 05:35:42 INFO - PROCESS | 1691 | [#7] imaging-desaturate Cycles:10 Average:106.10 Median:90.50 stddev:22.04 (24.4%) stddev-sans-first:22.69 05:35:42 INFO - PROCESS | 1691 | Values: 91.0 109.0 90.0 91.0 93.0 87.0 144.0 143.0 119.0 94.0 05:35:42 INFO - PROCESS | 1691 | 05:35:42 INFO - PROCESS | 1691 | [#8] json-parse-financial Cycles:10 Average:63.50 Median:62.00 stddev:4.90 (7.9%) stddev-sans-first:5.17 05:35:42 INFO - PROCESS | 1691 | Values: 62.0 62.0 61.0 62.0 61.0 61.0 63.0 77.0 65.0 61.0 05:35:42 INFO - PROCESS | 1691 | 05:35:42 INFO - PROCESS | 1691 | [#9] json-stringify-tinderbox Cycles:10 Average:81.30 Median:67.50 stddev:17.43 (25.8%) stddev-sans-first:17.81 05:35:42 INFO - PROCESS | 1691 | Values: 68.0 67.0 74.0 65.0 83.0 108.0 102.0 60.0 84.0 102.0 05:35:42 INFO - PROCESS | 1691 | 05:35:42 INFO - PROCESS | 1691 | [#10] stanford-crypto-aes Cycles:10 Average:93.80 Median:82.50 stddev:13.85 (16.8%) stddev-sans-first:14.13 05:35:42 INFO - PROCESS | 1691 | Values: 83.0 85.0 84.0 81.0 84.0 82.0 106.0 112.0 111.0 110.0 05:35:42 INFO - PROCESS | 1691 | 05:35:42 INFO - PROCESS | 1691 | [#11] stanford-crypto-ccm Cycles:10 Average:147.10 Median:146.50 stddev:14.00 (9.6%) stddev-sans-first:14.61 05:35:42 INFO - PROCESS | 1691 | Values: 140.0 143.0 145.0 148.0 150.0 135.0 134.0 134.0 166.0 176.0 05:35:42 INFO - PROCESS | 1691 | 05:35:42 INFO - PROCESS | 1691 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.30 Median:173.00 stddev:3.33 (1.9%) stddev-sans-first:3.43 05:35:42 INFO - PROCESS | 1691 | Values: 170.0 173.0 172.0 176.0 164.0 173.0 173.0 173.0 174.0 175.0 05:35:42 INFO - PROCESS | 1691 | 05:35:42 INFO - PROCESS | 1691 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:80.30 Median:70.00 stddev:17.77 (25.4%) stddev-sans-first:18.37 05:35:42 INFO - PROCESS | 1691 | Values: 69.0 70.0 70.0 58.0 57.0 82.0 96.0 102.0 98.0 101.0 05:35:42 INFO - PROCESS | 1691 | -------- Summary: end -------- 05:35:42 INFO - PROCESS | 1691 | 05:35:43 INFO - TEST-INFO | 1691: exit 0 05:35:43 INFO - TEST-OK | kraken | took 60909ms 05:35:43 INFO - SUITE-END | took 1173s 05:35:43 INFO - Completed test suite (00:19:33) 05:35:43 INFO - javascript benchmark 05:35:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2841.5737639354093, 3533.4523148911912, 3554.573451185858, 3554.6267641897616, 3590.479380489829, 5256.894862102756, 5759.942400575993, 5767.961766881965, 5776.797812076578, 5786.508146807527, 5616.213730077792, 5738.196652468648, 5845.444682755142, 5870.530357571395, 5876.265466816648, 5250.737463126838, 5295.602829787762, 5317.0, 5330.680159190441, 5384.461553844623, 5252.658577192489, 5414.1608050752, 5438.83683489496, 5446.15584584388, 5483.259759932418, 4329.935050974238, 4819.662623616348, 5172.353455818023, 5365.946340536584, 5401.27082843815, 4362.629176519999, 5241.554467870235, 5294.258803767476, 5314.97342513285, 5343.412224655285, 5259.921101183485, 5262.947370526284, 5273.657212281228, 5318.946810531884, 5327.413984461706, 5590.636608620427, 5651.463111004448, 5738.7991420300505, 5751.166080918285, 5773.393793651648, 5189.66267192635, 6207.255129384503, 6237.251529816405, 6306.779262725782, 6310.652914089723, 5823.2138661280605, 6060.818175454743, 6266.436020758154, 6319.58922670025, 6379.425851673325, 5697.857553561153, 5834.883302333972, 5912.556558258062, 5912.704364781744, 5934.43622855828, 6275.6862156891975, 6296.464800492009, 6324.272708638474, 6333.176687030748, 6363.236411630634, 6045.546584006217, 6217.626942383471, 6228.719707613168, 6231.626102433868, 6256.7810126645345, 6171.938280617226, 6230.221222347207, 6270.2475702915945, 6308.0, 6314.778982735582, 6112.724927378279, 6128.846778830565, 6232.875342493125, 6257.405546473078, 6263.091851681572, 6139.26328840542, 6180.81457556274, 6233.657148856856, 6282.623042617458, 6299.118123462719, 4187.5184353799095, 4925.852224433273, 4976.676516026447, 4978.551930326288, 5011.6241281904, 4403.9779801100785, 4417.867463976086, 4423.62399196072, 4459.6655250855665, 4464.441944756905, 5698.31620205578, 5814.127880817827, 5815.360310365856, 5866.424189132484, 5917.082852157922, 6256.874862502816, 6257.092721555349, 6285.8742825143245, 6313.05304204373, 6322.810315690536, 5811.244538210006, 5834.281370936913, 5862.501687356537, 5879.764809407576, 5884.264466941632, 5345.518903298722, 5420.07858664028, 5450.3732070811575, 5462.535684466785, 5481.424450432727, 6301.0233413820015, 6315.179026726496, 6326.525510586633, 6339.429451349309, 6372.012338087604, 6313.936860631518, 6319.842003949938, 6326.6520341381665, 6339.2392912850755, 6361.109444677842, 5318.521333080042, 5360.544353729821, 6022.698865056817, 6230.7507699691505, 6283.151774510383, 5133.614978876674, 6178.876422471615, 6249.312575616769, 6265.216847894013, 6297.590656607306, 6210.596311239755, 6306.842328941813, 6334.144890439732, 6338.429541341301, 6344.841378965378, 5915.142304365929, 6067.180930574493, 6136.325004249618, 6167.784127555514, 6190.628562286277, 4873.561379475864, 6191.473724733448, 6196.0705894116245, 6206.565540412128, 6231.40801623845, 5980.790672326448, 5988.6706231157705, 6038.818835434944, 6041.939580604314, 6045.335013148638, 6014.9097763534455, 6139.785107521216, 6146.494108943338, 6175.413335733098, 6183.876322473436, 6093.268807743099, 6098.329183789869, 6101.786437474667, 6115.663638499747, 6139.6009259398], "value": 5709.995297871253, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [516.220507034378, 542.560525973961, 561.200289587338, 564.587850868866, 627.1251604012402, 37273.0, 38127.66553170639, 38161.61838381615, 38162.61837381625, 38211.42682859759, 40649.79675101628, 41542.961425964364, 41546.05312495308, 41594.792026039824, 41690.54083107092, 9546.28402869784, 9713.902860971406, 9771.657991970282, 9803.362781419184, 9812.901870981306, 9587.185089267421, 9672.56473458696, 9683.951580242125, 9693.903060969371, 9717.222622190244, 9656.372335798185, 9670.129688328048, 9684.08001239881, 9746.951265243664, 9748.610055597803, 9628.566714497865, 9651.227901767877, 9664.420134791935, 9690.515474226295, 9691.370060946016, 9609.279304052225, 9630.27772917034, 9678.612855485771, 9684.467354295512, 9708.90291097087, 38155.664551740556, 38212.42681359782, 38451.807740961114, 38557.0, 38649.80675096607, 2726.6046423268713, 3082.3355388090627, 3121.562981182636, 3410.880619178317, 3664.0839790052487, 25893.223203303933, 26558.804853781625, 27052.0, 27442.0, 27512.449751004868, 2643.0, 2647.113217072283, 2722.6460560127257, 2758.8758505867286, 2763.4196818668106, 301925.0, 303488.0, 305098.0, 306230.0, 307575.0, 31787.523187152212, 32925.34149317048, 35442.46836297458, 36478.63521364806, 36557.90326290215, 34376.484352734726, 34651.826740866636, 34911.4763278551, 35591.64408355884, 35821.925342239774, 14601.342939567745, 14747.483838065617, 14959.700805983819, 14963.0, 14977.251137443085, 12131.33277669719, 12207.023438124928, 12306.876931230752, 12593.559225427067, 12750.97992160625, 8012.959935200287, 8127.796805079802, 8130.918690813134, 8259.132791056853, 8340.040895296997, 2998.3253767902484, 3402.0474267205223, 3459.7924124552605, 3460.723142148622, 3462.9480557791653, 966.8356379415517, 991.0188912976101, 1060.7242117049623, 1104.9779004419865, 1106.4191299567792, 29686.851565742032, 29778.0, 30108.397832043236, 30554.84722576373, 30557.541636875463, 1070.2775626452114, 1120.1542835159403, 1140.5950887435008, 1165.6153469355093, 1193.7731830952264, 15366.154861482726, 15568.455104071303, 15652.530424087296, 15693.136877471621, 15724.0, 574800.2519974831, 578979.0, 589934.1006589965, 590181.0, 590250.0, 60885.391146087975, 61798.0, 62289.68855155695, 62529.0, 62749.0, 1453.324204245031, 1459.292243262017, 1479.0534058202543, 1492.7611582147022, 1494.2827442827504, 29448.822047117876, 29526.557101643062, 29596.0, 30377.0, 30441.0, 54025.72987135039, 54348.456515434336, 54701.0, 55002.724986374815, 55188.44811551833, 465905.0, 466072.0, 468404.9739253846, 469495.0, 469514.0, 23244.767552324938, 26858.79135438949, 27438.0, 27777.166684999484, 41926.79036604797, 38816.64141755025, 42797.14405711931, 43078.49225277226, 43854.34218486661, 43962.0, 25754.35614109662, 30020.704961742853, 36471.205496564646, 38310.659126930426, 40123.79938100408, 35975.64024359723, 37032.629673703996, 37304.88085357444, 37762.0, 37833.05417364478, 37335.0, 37809.432858507695, 39060.80469597634, 39463.21073578455, 39581.40627890641, 26953.191404257905, 30347.241318967204, 30960.225994350323, 32240.516392254605, 32478.512822308156, 20074.39776806698, 24153.63769543423, 24186.153484628656, 24224.394390140387, 24455.633165502175], "value": 17904.277925059996, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.27245876448103, 375.97556158849653, 388.37083924043037, 396.2530629762897, 405.12493015087415, 17801.82198178018, 20003.099860506274, 20010.199592016303, 21027.948602569886, 21100.99999999998, 11498.19512634114, 11871.050315974722, 12221.450034748435, 12240.449179786909, 12262.141650084497, 5402.189671549269, 5574.9721251393885, 5608.915866262009, 5610.8597285068, 5615.887682246353, 3957.8812635620834, 5863.706814659271, 6190.814275571717, 6211.068339749051, 6216.8445788855415, 5922.200502932092, 5991.453632206295, 6185.59793613416, 6187.1956645636, 6205.9379406206035, 5338.62629615927, 6113.358097399798, 6147.754089836401, 6151.138840562325, 6153.569250152514, 4955.752212389366, 5344.866378340534, 5359.330083739533, 5782.190493330937, 5952.839196356712, 10817.35095894241, 12493.0, 13000.544980925622, 13070.215787052808, 13193.604191874163, 1975.2790231565518, 2019.798020197983, 2032.3699653107583, 2099.286242677482, 2163.578102270058, 9327.037773359842, 9330.906690933072, 9382.296327775444, 9431.339806213567, 9612.615495380176, 1662.5677323895752, 1665.5586269638434, 1719.9054052027134, 1742.912854357277, 1777.5022993561824, 115291.0, 118208.22687659692, 118533.0, 119286.46945004145, 123044.0, 11536.307821530734, 11801.468933897995, 11864.228825126513, 11931.284122952477, 11960.880391196151, 14975.251237438084, 15017.849821501864, 15026.549203523911, 15031.849681503263, 15040.924795375953, 7257.709691612276, 8713.520756358334, 8818.162274583905, 8822.558872056372, 8830.64677412909, 8553.700620478254, 8626.18051285127, 8651.437656552303, 8753.824923501494, 8758.124187581318, 4236.813692166198, 4362.280223763044, 4683.437987441529, 4692.835750748707, 4693.6949098308505, 1814.854811615094, 1978.851586131046, 2006.277740013594, 2021.2218295956163, 2023.6559784836613, 905.4838741917044, 956.3401253135314, 1028.598846449885, 1038.8234000219982, 1081.4376524207369, 19791.40625781229, 19967.800321996885, 21593.136274549157, 21813.23653672114, 22107.447313817283, 961.9374740641836, 962.8363178259713, 980.4362491567377, 1014.4623349624711, 1088.8747794003632, 9959.751006224902, 10819.242653014213, 10977.560897564008, 10978.396188209565, 11031.338119712844, 624244.0, 631850.0, 633597.0, 638861.0, 641982.0, 132802.33598831945, 135160.0, 135347.32326338306, 135852.0, 142287.0, 1066.7066556696816, 1080.1574771678104, 1082.32895604724, 1094.3269889018197, 1102.4763237462303, 18345.08274586292, 18427.72358414598, 18478.353257635918, 18537.721934171266, 18552.350667726565, 53954.46045539651, 54103.64740881509, 54194.72902635462, 54673.0, 54863.0, 78659.82010269965, 79655.60172199101, 79726.0, 80681.0, 81276.0, 7915.960420197862, 8020.303046955857, 8121.228483294078, 8361.581920904053, 8405.159484051546, 9324.766880828034, 11763.353015584225, 12282.570110046105, 12466.937665311616, 12512.249265044127, 8699.782030515606, 8811.559422029, 11057.0, 11456.713582160513, 11506.424678766196, 10819.837702434312, 10828.458577071273, 10988.0, 11006.724831879266, 11497.54009839597, 11268.774624507629, 11317.71705707364, 11406.828897566376, 11463.713407164887, 11544.538218471504, 6088.908666369919, 6703.396694297341, 8068.47554908929, 8095.676172953016, 8263.586820659064, 10180.796384072426, 11303.717407064889, 11321.320720756781, 11432.771344573228, 11619.0], "value": 9243.192737988975, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1528.1060579560956, 1595.4894433781187, 1857.684193687073, 1934.8452123830095, 1961.1469010980225, 8067.19328067193, 8592.742217733463, 8695.130486951302, 8939.284857211433, 8944.373893827435, 8612.052674205848, 8626.353023523245, 8666.350023748213, 8761.737147885558, 8764.8685269721, 2487.818286314, 2507.360623041122, 2520.7479252074827, 2526.557852375836, 2554.9361265968314, 2448.951020979588, 2501.1496091328945, 2517.647529345893, 2529.2285852814884, 2532.556802559554, 2500.612405077207, 2511.3972646564876, 2512.108201588437, 2524.1544082732307, 2525.6464095026713, 2490.1284550407395, 2499.4126380300704, 2504.8121390895662, 2506.949861002788, 2520.395105174763, 2099.653557163066, 2459.360566252787, 2485.0680994627014, 2502.361897716089, 2507.009731156183, 8757.2994160467, 8790.824183516293, 8800.295976321879, 8855.24730397917, 8873.51195684237, 5334.839954801362, 5501.587380946445, 5526.530244929146, 5539.25220095286, 5548.639338442988, 3649.6715295623258, 3690.372636651749, 3698.42674385468, 3732.618930995522, 3734.7012239021083, 1015.9542820573092, 1038.190211634926, 1039.4802598700649, 1052.3159946035062, 1076.682378698286, 9372.390794598397, 10566.577336906514, 11684.591039313584, 11782.823257651142, 11848.526058957632, 4776.0, 4811.230203167476, 4849.248366503198, 4922.8769280768265, 5019.347484826949, 8710.390272680977, 8975.519039358433, 9950.0, 9954.552045157985, 10074.992500749866, 5422.267993820863, 5505.889882202414, 5514.80698175562, 5544.0575102232715, 5558.194061861008, 4491.034427598082, 4884.291777692286, 5047.1419858623385, 5052.469490703424, 5116.872078198075, 4080.714349995472, 4089.7750623715388, 4100.7334523255895, 4107.954375950913, 4145.17096580679, 1267.2903174222465, 1316.1905428161613, 1356.4709763192363, 1378.8345398552037, 1382.9930850345684, 877.8376000439939, 878.9384743068051, 945.4138434170858, 952.9428234305964, 962.9277804164716, 5728.198052272685, 5795.507381872504, 5988.0, 6126.846828829315, 6262.780802671976, 441.56505841745854, 462.736240343008, 482.07922867323947, 483.4440393547378, 485.4174990012, 4016.2770701273466, 4047.3928910663635, 4053.088055187618, 4075.5924407559005, 4103.753774773523, 10442.2168337375, 10836.0, 10866.293690910066, 10978.890211097947, 10985.725356865982, 10453.425061621683, 10667.093297069681, 10870.836937445945, 10925.07136893373, 10972.835407468894, 8150.410669919427, 8154.551499667576, 8229.917700823034, 8322.126176751475, 8346.0, 5869.765209391577, 5992.340842507408, 5998.580099393175, 6064.211652485149, 6087.726052327567, 9769.16962058233, 9898.158656514277, 10018.048285412875, 10059.949700251453, 10240.64157754475, 9991.050850169222, 10152.883182849733, 10183.49082545855, 10299.897001029894, 10352.223583231138, 6831.6242606657115, 7263.709451621876, 7446.664900079401, 7515.060617422822, 7515.985341978763, 8311.0, 8352.039515455801, 8397.916020839713, 8407.663693452194, 8426.705065322683, 7273.490855640266, 7420.183779784112, 7423.406127509894, 7513.661885215288, 7566.697332106654, 7300.342969132804, 7338.266173382619, 7344.302291282534, 7424.851502969802, 7442.516236444614, 7000.754973576104, 7034.0, 7061.0, 7070.823229419306, 7114.075170227838, 4856.053069651384, 4870.0746858096845, 4988.251762235694, 5008.248762685481, 5146.948530514647, 3888.9222215556097, 4911.557959783637, 5014.047331007097, 5076.314697515936, 5176.870578235424], "value": 4586.01666569689, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.3909166662509, 317.985690643921, 326.5200155771017, 328.13209062030944, 332.91344250494865, 4763.666543341968, 6073.9392606073925, 6152.723127459264, 6173.228346456693, 6179.969100154499, 5999.490043346321, 6039.547033972448, 6048.2742070951435, 6053.33413324535, 6058.303295121052, 3657.7988210648405, 4274.743515389071, 4361.498427680825, 4479.10417916418, 4513.458384993789, 3593.1915319053264, 4488.214562451574, 4505.301678239878, 4540.18276710192, 4554.043650833329, 3648.296978566757, 3753.887383378503, 4475.687990886753, 4480.103979204156, 4510.436754963591, 3642.7978767006825, 4368.728261304104, 4460.5316441773475, 4463.531329210418, 4487.708298960573, 3801.3727734923705, 3813.733038687293, 3832.367659336206, 3837.6184573553496, 3850.3839385698434, 4547.840825571089, 4706.5293470653005, 5739.483446489836, 5742.569307301927, 5769.769209231626, 4542.977829988259, 4552.658550608718, 4878.243872199815, 5166.4575219601775, 5167.638265321429, 5799.811038737049, 6045.758169673208, 6248.0, 6344.746210151635, 6388.680565971683, 3887.9805600971813, 4202.9789851050855, 4205.936910946339, 4207.516135644379, 4245.575442455761, 5520.834374968757, 6146.231721034871, 6699.832504187386, 6735.764248251287, 6744.865102697919, 3549.414346632777, 3787.905302367463, 3993.600639935983, 4184.979075104666, 4277.251480990814, 3953.426753120839, 4082.918026722921, 4333.71830830995, 4336.631386332134, 4353.847615333448, 4901.43633482147, 4905.828296009622, 4919.877003074952, 5084.898302033938, 5098.107831129538, 5610.747516361774, 5862.325832529314, 5911.438413350811, 6352.618842869442, 6442.130312407859, 1278.878506541877, 1427.200767570164, 1485.4578079001087, 1488.1889370293216, 1491.4779827060443, 939.7086903060073, 962.129273007929, 970.9465979371062, 970.9902900971041, 972.6741541583507, 5935.287765468085, 5977.282726072899, 5980.0, 5987.2815262168815, 6000.399960003964, 6635.834104147339, 6649.105005073531, 6658.400743933069, 6662.800115996528, 6687.76592819249, 5694.402087780806, 5998.130271110752, 6066.27111273285, 6077.24034495688, 6117.694115294217, 2809.4802461544677, 2957.6450825900597, 3463.497792820027, 3514.4903988921465, 3567.678908898212, 5877.706114694248, 6470.0, 6972.0, 7002.649867506604, 7003.089598352181, 5707.857303567444, 5747.597668163272, 6948.895766563505, 6949.548279361826, 6984.092068031225, 6767.932320676829, 6979.30206979298, 7028.297170283033, 7063.223045464994, 7070.080889484334, 6474.384933431316, 6670.199576050905, 6706.6981985811735, 6711.697973591297, 6729.461643068641, 5796.94203057964, 6368.172137622079, 6603.042558829039, 6648.0, 6672.766453174309, 5221.817236396708, 6961.89557156633, 7005.0578756921195, 7012.649367531501, 7026.962180967462, 4001.339778936443, 4089.6728261739586, 4270.893227669333, 4292.034292284211, 4310.43964284641, 5393.541548968382, 6552.0, 6642.634655093823, 6650.9667451662435, 6701.615684079508, 5725.370209276893, 5926.111083337361, 5928.110783382527, 6317.6209427434505, 6461.483081353386, 4986.80052797884, 6133.815985520441, 6430.260520040255, 6469.547131700736, 6813.7955861324235, 5290.285811415563, 6241.282252541016, 6285.779997700059, 6294.545127400409, 6307.432331090224, 5805.76776928935, 6023.186869772525, 6384.010478375859, 6441.871162576816, 6476.481881449567, 5545.833624991256, 5985.491233245223, 6268.091126786499, 6350.555461117678, 6363.96818015907], "value": 4585.494884437663, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [665.477600083934, 724.4494184419846, 749.6364263332283, 753.1940823319047, 779.1001393390633, 14980.850191498082, 15114.773278400833, 15265.542033738979, 15303.0, 15526.922365388158, 11041.779164416706, 13888.23614701191, 14174.220417877013, 14222.359993800277, 14477.493287734931, 899.2850683706437, 914.095045904553, 917.101240784032, 919.2829592917535, 919.8160367926408, 723.2550473012806, 883.3507372081515, 900.0234745301323, 912.8219997100568, 920.1028996728203, 961.9519024048805, 963.1620490173559, 964.5273815830262, 965.1217392173124, 967.3905439573094, 936.4053825820653, 957.8850537935423, 957.9712608621752, 958.9616415343413, 961.4904100826573, 693.0747452151384, 709.0, 711.2105562825311, 714.1144980224536, 715.6529083394549, 14901.627459313495, 15182.772258416133, 15190.468333608382, 15258.771118433233, 15266.542003739905, 12705.0, 12720.681982950407, 12722.745545089047, 12738.872611273953, 12960.092793504458, 10067.597296108146, 10131.746706332327, 10135.493225338703, 10338.379697218115, 10469.68590942273, 2858.5569236768333, 3260.9890933810593, 3274.001429563982, 3509.350770107538, 3541.061618671054, 70792.64603677, 84241.57879210626, 85776.99999999937, 86906.0, 88262.0, 6289.811305660837, 7688.654010569538, 7701.422393320523, 7705.807354816062, 7723.382129429632, 21726.0, 21758.238461653767, 21768.673469897964, 21770.564588708137, 21771.129154833947, 5773.6824474654295, 7257.0565826442225, 7388.187299397062, 7555.962220188864, 7635.350995165362, 4040.2323558523785, 4060.5540475810835, 4066.0851308455362, 4068.6745060395097, 4083.34666453372, 3545.8758943436856, 3589.1565482111682, 3591.371509985794, 3615.94576081359, 3624.655657712513, 626.9341719119518, 640.8173670503884, 646.3213625693004, 648.7340190521863, 649.078308801503, 553.3995614757952, 582.2256398989334, 590.9854881331895, 592.5378205000106, 604.8125081224835, 6436.452901503331, 7603.0, 7697.461177717618, 7822.726204582812, 8044.074931382966, 511.37867490998616, 516.1947362115037, 542.7937383794133, 543.2801537962137, 586.9941300587025, 3780.8487660493824, 3829.7319187657154, 3847.2690188864026, 3862.7102967277565, 3883.514560679915, 92170.61744073844, 93589.0, 94015.0, 94172.0, 96080.51959740157, 78261.0, 78318.60840695874, 78766.0, 78869.60565197137, 79529.60235198903, 66746.66626666836, 67691.98462023074, 69041.61916761637, 69808.90573282809, 70494.64752676204, 7230.602316872622, 7377.114746230487, 7603.353714934293, 7655.349295309961, 7671.347935425551, 36437.36031878519, 42584.0, 42636.78681606572, 43729.0, 43745.562544375425, 53814.654633632905, 56429.0, 65126.67436662786, 65585.67207164124, 66530.0, 9031.729048128567, 10984.615538456115, 11032.779344413228, 11060.723481913017, 11193.552257909918, 10393.908639593037, 11918.344491052729, 11978.880211197777, 12351.19717218378, 12527.18573292733, 10536.683899483029, 10651.455538946964, 11269.887301126884, 11547.422628868691, 11756.529738810352, 8857.911420885708, 11253.941064025288, 11413.828792568283, 11443.0, 12577.119601627799, 10574.524146413276, 11086.667399978014, 11340.376279304717, 11467.0, 11790.469428875893, 9558.474283914451, 9596.376235544667, 9860.30977831545, 9965.950170249102, 10253.436061016417, 9026.368154229142, 9603.183729383081, 9716.125548700651, 9745.853812192681, 9814.2148628111], "value": 6742.9517047226645, "unit": "score"}], "name": "dromaeo_css", "value": 7153.532441989282, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 109.0, 146.0, 175.0, 145.0, 134.0, 159.0, 116.0, 116.0, 116.0], "value": 133.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [192.0, 122.0, 201.0, 206.0, 153.0, 145.0, 141.0, 124.0, 169.0, 123.0], "value": 157.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 204.0, 272.0, 278.0, 201.0, 223.0, 196.0, 193.0, 201.0, 199.0], "value": 216.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 110.0, 65.0, 135.0, 71.0, 82.0, 92.0, 62.0, 87.0, 84.0], "value": 87.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 85.0, 133.0, 76.0, 83.0, 87.0, 153.0, 122.0, 117.0, 152.0], "value": 108.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 130.0, 91.0, 88.0, 90.0, 106.0, 134.0, 88.0, 92.0, 86.0], "value": 104.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 105.0, 95.0, 96.0, 150.0, 96.0, 94.0, 95.0], "value": 101.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 109.0, 90.0, 91.0, 93.0, 87.0, 144.0, 143.0, 119.0, 94.0], "value": 106.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 61.0, 62.0, 61.0, 61.0, 63.0, 77.0, 65.0, 61.0], "value": 63.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 74.0, 65.0, 83.0, 108.0, 102.0, 60.0, 84.0, 102.0], "value": 81.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 84.0, 81.0, 84.0, 82.0, 106.0, 112.0, 111.0, 110.0], "value": 93.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 143.0, 145.0, 148.0, 150.0, 135.0, 134.0, 134.0, 166.0, 176.0], "value": 147.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 173.0, 172.0, 176.0, 164.0, 173.0, 173.0, 173.0, 174.0, 175.0], "value": 172.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 70.0, 58.0, 57.0, 82.0, 96.0, 102.0, 98.0, 101.0], "value": 80.3, "unit": "score"}], "name": "kraken", "value": 1653.1999999999998, "alertThreshold": 2.0}]} 05:35:43 INFO - Return code: 0 05:35:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:35:44 ERROR - # TBPL SUCCESS # 05:35:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:35:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:35:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:35:44 INFO - 2016-04-17 05:35:44.058 screenresolution[1696:11806] starting screenresolution argv=screenresolution get 05:35:44 INFO - 2016-04-17 05:35:44.061 screenresolution[1696:11806] Display 0: 1600x1200x32@60 05:35:44 INFO - 2016-04-17 05:35:44.067 screenresolution[1697:11809] starting screenresolution argv=screenresolution list 05:35:44 INFO - Available Modes on Display 0 05:35:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:35:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:35:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:35:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:35:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:35:44 INFO - Intel Iris: 05:35:44 INFO - Chipset Model: Intel Iris 05:35:44 INFO - Type: GPU 05:35:44 INFO - Bus: Built-In 05:35:44 INFO - VRAM (Dynamic, Max): 1536 MB 05:35:44 INFO - Vendor: Intel (0x8086) 05:35:44 INFO - Device ID: 0x0a2e 05:35:44 INFO - Revision ID: 0x0009 05:35:44 INFO - Displays: 05:35:44 INFO - Display: 05:35:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:35:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:35:44 INFO - Display Serial Number: 1600x1200 60 05:35:44 INFO - Main Display: Yes 05:35:44 INFO - Mirror: Off 05:35:44 INFO - Online: Yes 05:35:44 INFO - Rotation: Supported 05:35:44 INFO - Return code: 0 05:35:44 INFO - Running post-action listener: _resource_record_post_action 05:35:44 INFO - Running post-run listener: _resource_record_post_run 05:35:45 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 32984576; Write bytes: 698828288; Read time: 325; Write time: 2084 05:35:45 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 390042112; Write bytes: 384321024; Read time: 29503; Write time: 722 05:35:45 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 31198720; Write bytes: 301113344; Read time: 306; Write time: 1332 05:35:45 INFO - Running post-run listener: _upload_blobber_files 05:35:45 INFO - Blob upload gear active. 05:35:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:35:45 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 05:35:45 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:35:45 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:35:45 INFO - (blobuploader) - INFO - Open directory for files ... 05:35:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:35:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:35:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:35:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:35:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:35:46 INFO - (blobuploader) - INFO - Done attempting. 05:35:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:35:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:35:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:35:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:35:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:35:47 INFO - (blobuploader) - INFO - Done attempting. 05:35:47 INFO - (blobuploader) - INFO - Iteration through files over. 05:35:47 INFO - Return code: 0 05:35:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:35:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:35:47 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d4249aa0b4f73be49c5d04a0d8fae325a3e84cedef155090fa55a4c97018acded5391cd0cf59b3d02ff158ff54206596d430034ad075c53eaad37dfe8512896a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a10ca54c328fa38eddb112da3e3f483b702a9d10ea37dd87075107d260dfe2197f7f92b06cadcd239a4d2ecdf1247cd0bc9d04e0aa177c52d122d31294a16f3"} 05:35:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:35:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:35:47 INFO - Contents: 05:35:47 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d4249aa0b4f73be49c5d04a0d8fae325a3e84cedef155090fa55a4c97018acded5391cd0cf59b3d02ff158ff54206596d430034ad075c53eaad37dfe8512896a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a10ca54c328fa38eddb112da3e3f483b702a9d10ea37dd87075107d260dfe2197f7f92b06cadcd239a4d2ecdf1247cd0bc9d04e0aa177c52d122d31294a16f3"} 05:35:47 INFO - Running post-run listener: copy_logs_to_upload_dir 05:35:47 INFO - Copying logs to upload dir... 05:35:47 INFO - mkdir: /builds/slave/test/build/upload/logs 05:35:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1283.044848 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2016-04-17 05:35:48.006501) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-17 05:35:48.010090) ========= 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.J3GLF0xqQJ/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.6qSqrT1wbF/Listeners TMPDIR=/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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/Firefox/sha512/d4249aa0b4f73be49c5d04a0d8fae325a3e84cedef155090fa55a4c97018acded5391cd0cf59b3d02ff158ff54206596d430034ad075c53eaad37dfe8512896a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a10ca54c328fa38eddb112da3e3f483b702a9d10ea37dd87075107d260dfe2197f7f92b06cadcd239a4d2ecdf1247cd0bc9d04e0aa177c52d122d31294a16f3"} build_url:https://queue.taskcluster.net/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011045 build_url: 'https://queue.taskcluster.net/v1/task/X91S8eGhRqij2LugsBv9LA/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d4249aa0b4f73be49c5d04a0d8fae325a3e84cedef155090fa55a4c97018acded5391cd0cf59b3d02ff158ff54206596d430034ad075c53eaad37dfe8512896a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4a10ca54c328fa38eddb112da3e3f483b702a9d10ea37dd87075107d260dfe2197f7f92b06cadcd239a4d2ecdf1247cd0bc9d04e0aa177c52d122d31294a16f3"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-17 05:35:48.039929) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-17 05:35:48.040604) ========= 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.J3GLF0xqQJ/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.6qSqrT1wbF/Listeners TMPDIR=/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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.005232 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-17 05:35:48.093111) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-17 05:35:48.093806) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-17 05:35:48.094631) ========= ========= Total master_lag: 0.47 =========