builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0144 starttime: 1463717305.71 results: success (0) buildid: 20160519191805 builduid: 758043963a694a0b8e389b65cf50b56c revision: 9ba18aae69e626a3f83560df321d91e42316083a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-19 21:08:25.706507) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-19 21:08:25.711411) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-19 21:08:25.711710) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-19 21:08:25.734747) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 21:08:25.735059) ========= 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.qskAUs0bnP/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.VBofgv6Dtn/Listeners TMPDIR=/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-19 21:08:25-- 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% 159M=0s 2016-05-19 21:08:26 (159 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.445075 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 21:08:26.199755) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 21:08:26.200066) ========= 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.qskAUs0bnP/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.VBofgv6Dtn/Listeners TMPDIR=/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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.027383 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 21:08:26.248154) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-19 21:08:26.248511) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 9ba18aae69e626a3f83560df321d91e42316083a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 9ba18aae69e626a3f83560df321d91e42316083a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qskAUs0bnP/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.VBofgv6Dtn/Listeners TMPDIR=/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-19 21:08:26,324 truncating revision to first 12 chars 2016-05-19 21:08:26,324 Setting DEBUG logging. 2016-05-19 21:08:26,324 attempt 1/10 2016-05-19 21:08:26,324 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/9ba18aae69e6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-19 21:08:27,327 unpacking tar archive at: mozilla-aurora-9ba18aae69e6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.258445 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-19 21:08:27.525040) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-19 21:08:27.525365) ========= script_repo_revision: 9ba18aae69e626a3f83560df321d91e42316083a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-19 21:08:27.525759) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-19 21:08:27.526023) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-19 21:08:27.540895) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 13 secs) (at 2016-05-19 21:08:27.541303) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qskAUs0bnP/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.VBofgv6Dtn/Listeners TMPDIR=/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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 21:08:27 INFO - MultiFileLogger online at 20160519 21:08:27 in /builds/slave/test 21:08:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 21:08:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:08:27 INFO - {'append_to_log': False, 21:08:27 INFO - 'base_work_dir': '/builds/slave/test', 21:08:27 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 21:08:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:08:27 INFO - 'branch': 'Mozilla-Aurora', 21:08:27 INFO - 'buildbot_json_path': 'buildprops.json', 21:08:27 INFO - 'config_files': ('talos/mac_config.py',), 21:08:27 INFO - 'default_actions': ('clobber', 21:08:27 INFO - 'read-buildbot-config', 21:08:27 INFO - 'download-and-extract', 21:08:27 INFO - 'populate-webroot', 21:08:27 INFO - 'create-virtualenv', 21:08:27 INFO - 'install', 21:08:27 INFO - 'run-tests'), 21:08:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:08:27 INFO - 'download_minidump_stackwalk': True, 21:08:27 INFO - 'download_symbols': 'ondemand', 21:08:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:08:27 INFO - 'tooltool.py': '/tools/tooltool.py', 21:08:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:08:27 INFO - '/tools/misc-python/virtualenv.py')}, 21:08:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:08:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:08:27 INFO - 'installer_path': 'installer.exe', 21:08:27 INFO - 'log_level': 'info', 21:08:27 INFO - 'log_name': 'talos', 21:08:27 INFO - 'log_to_console': True, 21:08:27 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:08:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:08:27 INFO - 'opt_config_files': (), 21:08:27 INFO - 'pip_index': False, 21:08:27 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:08:27 INFO - 'cmd': ('bash', 21:08:27 INFO - '-c', 21:08:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:08:27 INFO - 'enabled': True, 21:08:27 INFO - 'halt_on_failure': False, 21:08:27 INFO - 'name': 'check_screen_resolution'},), 21:08:27 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:08:27 INFO - 'cmd': ('bash', 21:08:27 INFO - '-c', 21:08:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:08:27 INFO - 'enabled': True, 21:08:27 INFO - 'halt_on_failure': False, 21:08:27 INFO - 'name': 'check_screen_resolution'},), 21:08:27 INFO - 'run_cmd_checks_enabled': True, 21:08:27 INFO - 'sps_profile': False, 21:08:27 INFO - 'sps_profile_interval': 0, 21:08:27 INFO - 'suite': 'dromaeojs', 21:08:27 INFO - 'system_bits': '32', 21:08:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:08:27 INFO - 'title': 't-yosemite-r7-0144', 21:08:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:08:27 INFO - 'use_talos_json': True, 21:08:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:08:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:08:27 INFO - 'work_dir': 'build'} 21:08:27 INFO - ##### 21:08:27 INFO - ##### Running clobber step. 21:08:27 INFO - ##### 21:08:27 INFO - Running pre-action listener: _resource_record_pre_action 21:08:27 INFO - Running main action method: clobber 21:08:27 INFO - rmtree: /builds/slave/test/build 21:08:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:08:28 INFO - Running post-action listener: _resource_record_post_action 21:08:28 INFO - ##### 21:08:28 INFO - ##### Running read-buildbot-config step. 21:08:28 INFO - ##### 21:08:28 INFO - Running pre-action listener: _resource_record_pre_action 21:08:28 INFO - Running main action method: read_buildbot_config 21:08:28 INFO - Using buildbot properties: 21:08:28 INFO - { 21:08:28 INFO - "project": "", 21:08:28 INFO - "product": "firefox", 21:08:28 INFO - "script_repo_revision": "production", 21:08:28 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 21:08:28 INFO - "repository": "", 21:08:28 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 21:08:28 INFO - "buildid": "20160519191805", 21:08:28 INFO - "pgo_build": "False", 21:08:28 INFO - "basedir": "/builds/slave/test", 21:08:28 INFO - "buildnumber": 321, 21:08:28 INFO - "slavename": "t-yosemite-r7-0144", 21:08:28 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 21:08:28 INFO - "platform": "yosemite_r7", 21:08:28 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 21:08:28 INFO - "branch": "mozilla-aurora", 21:08:28 INFO - "repo_path": "releases/mozilla-aurora", 21:08:28 INFO - "revision": "9ba18aae69e626a3f83560df321d91e42316083a", 21:08:28 INFO - "stage_platform": "macosx64", 21:08:28 INFO - "builduid": "758043963a694a0b8e389b65cf50b56c", 21:08:28 INFO - "slavebuilddir": "test" 21:08:28 INFO - } 21:08:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/L-vWqz3XQH2ICR74QtoRog/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 21:08:28 INFO - Running post-action listener: _resource_record_post_action 21:08:28 INFO - ##### 21:08:28 INFO - ##### Running download-and-extract step. 21:08:28 INFO - ##### 21:08:28 INFO - Running pre-action listener: _resource_record_pre_action 21:08:28 INFO - Running main action method: download_and_extract 21:08:28 INFO - mkdir: /builds/slave/test/build/tests 21:08:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:08:28 INFO - https://queue.taskcluster.net/v1/task/L-vWqz3XQH2ICR74QtoRog/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 21:08:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L-vWqz3XQH2ICR74QtoRog/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 21:08:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L-vWqz3XQH2ICR74QtoRog/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 21:08:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L-vWqz3XQH2ICR74QtoRog/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 21:08:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L-vWqz3XQH2ICR74QtoRog/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 21:08:58 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L-vWqz3XQH2ICR74QtoRog/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json: timed out 21:08:58 INFO - retry: attempt #1 caught exception: timed out 21:08:58 INFO - retry: Failed, sleeping 30 seconds before retrying 21:09:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L-vWqz3XQH2ICR74QtoRog/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #2 21:09:31 INFO - Downloaded 1199 bytes. 21:09:31 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 21:09:31 INFO - Using the following test package requirements: 21:09:31 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 21:09:31 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 21:09:31 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 21:09:31 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 21:09:31 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 21:09:31 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 21:09:31 INFO - u'jsshell-mac.zip'], 21:09:31 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 21:09:31 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 21:09:31 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 21:09:31 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 21:09:31 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 21:09:31 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 21:09:31 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 21:09:31 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 21:09:31 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 21:09:31 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 21:09:31 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 21:09:31 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 21:09:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:09:31 INFO - https://queue.taskcluster.net/v1/task/L-vWqz3XQH2ICR74QtoRog/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 21:09:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L-vWqz3XQH2ICR74QtoRog/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 21:09:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L-vWqz3XQH2ICR74QtoRog/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 21:09:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L-vWqz3XQH2ICR74QtoRog/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 21:09:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L-vWqz3XQH2ICR74QtoRog/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 21:09:35 INFO - Downloaded 18102467 bytes. 21:09:35 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:09:35 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 21:09:38 INFO - Return code: 0 21:09:38 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 21:09: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')]} 21:09:38 INFO - https://queue.taskcluster.net/v1/task/L-vWqz3XQH2ICR74QtoRog/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 21:09:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L-vWqz3XQH2ICR74QtoRog/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 21:09:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L-vWqz3XQH2ICR74QtoRog/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 21:09:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L-vWqz3XQH2ICR74QtoRog/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 21:09:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L-vWqz3XQH2ICR74QtoRog/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 21:09:39 INFO - Downloaded 18102467 bytes. 21:09:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:09:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 21:09:43 INFO - Return code: 0 21:09: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')]} 21:09:43 INFO - https://queue.taskcluster.net/v1/task/L-vWqz3XQH2ICR74QtoRog/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 21:09:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L-vWqz3XQH2ICR74QtoRog/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 21:09:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L-vWqz3XQH2ICR74QtoRog/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 21:09:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L-vWqz3XQH2ICR74QtoRog/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 21:09:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L-vWqz3XQH2ICR74QtoRog/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 21:09:44 INFO - Downloaded 10840145 bytes. 21:09:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:09:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 21:09:45 INFO - Return code: 0 21:09:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:09:45 INFO - https://queue.taskcluster.net/v1/task/L-vWqz3XQH2ICR74QtoRog/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 21:09:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L-vWqz3XQH2ICR74QtoRog/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 21:09:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L-vWqz3XQH2ICR74QtoRog/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 21:09:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L-vWqz3XQH2ICR74QtoRog/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 21:09:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/L-vWqz3XQH2ICR74QtoRog/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 21:09:51 INFO - Downloaded 114100708 bytes. 21:09:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/L-vWqz3XQH2ICR74QtoRog/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 21:09:51 INFO - mkdir: /builds/slave/test/properties 21:09:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:09:51 INFO - Writing to file /builds/slave/test/properties/build_url 21:09:51 INFO - Contents: 21:09:51 INFO - build_url:https://queue.taskcluster.net/v1/task/L-vWqz3XQH2ICR74QtoRog/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 21:09:53 INFO - Running post-action listener: _resource_record_post_action 21:09:53 INFO - Running post-action listener: set_extra_try_arguments 21:09:53 INFO - ##### 21:09:53 INFO - ##### Running populate-webroot step. 21:09:53 INFO - ##### 21:09:53 INFO - Running pre-action listener: _resource_record_pre_action 21:09:53 INFO - Running main action method: populate_webroot 21:09:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:09:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:09:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:09:53 INFO - '--tppagecycles', 21:09:53 INFO - '7'], 21:09:53 INFO - 'tests': ['tsvgm']}}, 21:09:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:09:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:09:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:09:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:09:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:09:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:09:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:09:53 INFO - 'talos_options': ['--disable-e10s'], 21:09:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:09:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:09:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:09:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:09:53 INFO - 'talos_options': ['--disable-e10s'], 21:09:53 INFO - 'tests': ['damp', 'tps']}, 21:09:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:09:53 INFO - 'tests': ['damp', 'tps']}, 21:09:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:09:53 INFO - 'tests': ['dromaeo_dom']}, 21:09:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:09:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:09:53 INFO - 'tests': ['a11yr', 21:09:53 INFO - 'ts_paint', 21:09:53 INFO - 'tpaint', 21:09:53 INFO - 'sessionrestore', 21:09:53 INFO - 'sessionrestore_no_auto_restore', 21:09:53 INFO - 'tabpaint']}, 21:09:53 INFO - 'other-e10s': {'tests': ['a11yr', 21:09:53 INFO - 'ts_paint', 21:09:53 INFO - 'tpaint', 21:09:53 INFO - 'sessionrestore', 21:09:53 INFO - 'sessionrestore_no_auto_restore', 21:09:53 INFO - 'tabpaint']}, 21:09:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:09:53 INFO - 'ts_paint', 21:09:53 INFO - 'tpaint', 21:09:53 INFO - 'sessionrestore', 21:09:53 INFO - 'sessionrestore_no_auto_restore', 21:09:53 INFO - 'tabpaint']}, 21:09:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:09:53 INFO - 'ts_paint', 21:09:53 INFO - 'tpaint', 21:09:53 INFO - 'sessionrestore', 21:09:53 INFO - 'sessionrestore_no_auto_restore', 21:09:53 INFO - 'tabpaint']}, 21:09:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:09:53 INFO - 'tests': ['a11yr', 21:09:53 INFO - 'ts_paint', 21:09:53 INFO - 'tpaint', 21:09:53 INFO - 'sessionrestore', 21:09:53 INFO - 'sessionrestore_no_auto_restore', 21:09:53 INFO - 'tabpaint']}, 21:09:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:09:53 INFO - 'tests': ['a11yr', 21:09:53 INFO - 'ts_paint', 21:09:53 INFO - 'tpaint', 21:09:53 INFO - 'sessionrestore', 21:09:53 INFO - 'sessionrestore_no_auto_restore', 21:09:53 INFO - 'tabpaint']}, 21:09:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:09:53 INFO - 'tests': ['tsvgx', 21:09:53 INFO - 'tsvgr_opacity', 21:09:53 INFO - 'tart', 21:09:53 INFO - 'tscrollx', 21:09:53 INFO - 'cart']}, 21:09:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:09:53 INFO - 'tsvgr_opacity', 21:09:53 INFO - 'tart', 21:09:53 INFO - 'tscrollx', 21:09:53 INFO - 'cart']}, 21:09:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:09:53 INFO - 'talos_options': ['--disable-e10s'], 21:09:53 INFO - 'tests': ['tp5o']}, 21:09:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:09:53 INFO - 'tests': ['tp5o']}, 21:09:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:09:53 INFO - 'talos_options': ['--disable-e10s', 21:09:53 INFO - '--xperf_path', 21:09:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:09:53 INFO - 'tests': ['tp5n']}, 21:09:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:09:53 INFO - 'talos_options': ['--xperf_path', 21:09:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:09:53 INFO - 'tests': ['tp5n']}}, 21:09:53 INFO - 'talos.zip': {'path': '', 21:09:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:09:53 INFO - Running post-action listener: _resource_record_post_action 21:09:53 INFO - ##### 21:09:53 INFO - ##### Running create-virtualenv step. 21:09:53 INFO - ##### 21:09:53 INFO - Running pre-action listener: _resource_record_pre_action 21:09:53 INFO - Running main action method: create_virtualenv 21:09:53 INFO - Creating virtualenv /builds/slave/test/build/venv 21:09:53 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 21:09:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:09:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:09:53 INFO - Using real prefix '/tools/python27' 21:09:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:09:53 INFO - Installing distribute.............................................................................................................................................................................................done. 21:09:56 INFO - Installing pip.................done. 21:09:56 INFO - Return code: 0 21:09:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:09:56 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')]} 21:09:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:09:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:09:56 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')]} 21:09:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:09:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:09:56 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 0x106edfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106eef420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc4355d860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d7ee00>, '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 0x106be7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d79b20>, '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.qskAUs0bnP/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.VBofgv6Dtn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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 21:09:56 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 21:09:56 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 21:09:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qskAUs0bnP/Render', 21:09:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:09:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:09:56 INFO - 'HOME': '/Users/cltbld', 21:09:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:09:56 INFO - 'LOGNAME': 'cltbld', 21:09:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:09:56 INFO - 'MOZ_NO_REMOTE': '1', 21:09:56 INFO - 'NO_EM_RESTART': '1', 21:09:56 INFO - 'PAGER': '/bin/cat', 21:09:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:09:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:09:56 INFO - 'PWD': '/builds/slave/test', 21:09:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:09:56 INFO - 'SHELL': '/bin/bash', 21:09:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VBofgv6Dtn/Listeners', 21:09:56 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 21:09:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:09:56 INFO - 'USER': 'cltbld', 21:09:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:09:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:09:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:09:56 INFO - 'XPC_FLAGS': '0x0', 21:09:56 INFO - 'XPC_SERVICE_NAME': '0', 21:09:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:09:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:09:57 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 21:09:57 INFO - Cleaning up... 21:09:57 INFO - Return code: 0 21:09:57 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 21:09:57 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')]} 21:09:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:09:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:09:57 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')]} 21:09:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:09:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:09:57 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 0x106edfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106eef420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc4355d860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d7ee00>, '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 0x106be7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d79b20>, '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.qskAUs0bnP/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.VBofgv6Dtn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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 21:09:57 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 21:09:57 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 21:09:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qskAUs0bnP/Render', 21:09:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:09:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:09:57 INFO - 'HOME': '/Users/cltbld', 21:09:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:09:57 INFO - 'LOGNAME': 'cltbld', 21:09:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:09:57 INFO - 'MOZ_NO_REMOTE': '1', 21:09:57 INFO - 'NO_EM_RESTART': '1', 21:09:57 INFO - 'PAGER': '/bin/cat', 21:09:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:09:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:09:57 INFO - 'PWD': '/builds/slave/test', 21:09:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:09:57 INFO - 'SHELL': '/bin/bash', 21:09:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VBofgv6Dtn/Listeners', 21:09:57 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 21:09:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:09:57 INFO - 'USER': 'cltbld', 21:09:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:09:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:09:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:09:57 INFO - 'XPC_FLAGS': '0x0', 21:09:57 INFO - 'XPC_SERVICE_NAME': '0', 21:09:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:09:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:09:57 INFO - Downloading/unpacking psutil>=0.7.1 21:09:57 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 21:09:57 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 21:09:57 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 21:09:57 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 21:09:57 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 21:09:57 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 21:10:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:10:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:10:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:10:00 INFO - Installing collected packages: psutil 21:10:00 INFO - Running setup.py install for psutil 21:10:00 INFO - building 'psutil._psutil_osx' extension 21:10:00 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 21:10:01 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 21:10:01 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 21:10:01 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 21:10:01 INFO - building 'psutil._psutil_posix' extension 21:10:01 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 21:10:01 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:10:01 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:10:01 INFO - ^ 21:10:01 INFO - 1 warning generated. 21:10:01 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 21:10:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:10:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:10:01 INFO - Successfully installed psutil 21:10:01 INFO - Cleaning up... 21:10:01 INFO - Return code: 0 21:10:01 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 21:10:01 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')]} 21:10:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:10:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:10:01 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')]} 21:10:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:10:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:10:01 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 0x106edfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106eef420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc4355d860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d7ee00>, '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 0x106be7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d79b20>, '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.qskAUs0bnP/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.VBofgv6Dtn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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 21:10:01 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 21:10:01 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 21:10:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qskAUs0bnP/Render', 21:10:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:10:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:10:01 INFO - 'HOME': '/Users/cltbld', 21:10:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:10:01 INFO - 'LOGNAME': 'cltbld', 21:10:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:10:01 INFO - 'MOZ_NO_REMOTE': '1', 21:10:01 INFO - 'NO_EM_RESTART': '1', 21:10:01 INFO - 'PAGER': '/bin/cat', 21:10:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:10:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:10:01 INFO - 'PWD': '/builds/slave/test', 21:10:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:10:01 INFO - 'SHELL': '/bin/bash', 21:10:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VBofgv6Dtn/Listeners', 21:10:01 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 21:10:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:10:01 INFO - 'USER': 'cltbld', 21:10:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:10:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:10:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:10:01 INFO - 'XPC_FLAGS': '0x0', 21:10:01 INFO - 'XPC_SERVICE_NAME': '0', 21:10:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:10:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:10:01 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 21:10:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:10:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:10:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:10:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:10:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:10:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:10:04 INFO - Downloading mozsystemmonitor-0.0.tar.gz 21:10:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:10:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 21:10:04 INFO - Installing collected packages: mozsystemmonitor 21:10:04 INFO - Running setup.py install for mozsystemmonitor 21:10:04 INFO - Successfully installed mozsystemmonitor 21:10:04 INFO - Cleaning up... 21:10:04 INFO - Return code: 0 21:10:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:10:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:10:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:10:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:10:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:10:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:10:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:10:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106edfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106eef420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc4355d860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d7ee00>, '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 0x106be7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d79b20>, '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.qskAUs0bnP/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.VBofgv6Dtn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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 21:10:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 21:10:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 21:10:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qskAUs0bnP/Render', 21:10:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:10:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:10:04 INFO - 'HOME': '/Users/cltbld', 21:10:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:10:04 INFO - 'LOGNAME': 'cltbld', 21:10:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:10:04 INFO - 'MOZ_NO_REMOTE': '1', 21:10:04 INFO - 'NO_EM_RESTART': '1', 21:10:04 INFO - 'PAGER': '/bin/cat', 21:10:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:10:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:10:04 INFO - 'PWD': '/builds/slave/test', 21:10:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:10:04 INFO - 'SHELL': '/bin/bash', 21:10:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VBofgv6Dtn/Listeners', 21:10:04 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 21:10:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:10:04 INFO - 'USER': 'cltbld', 21:10:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:10:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:10:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:10:04 INFO - 'XPC_FLAGS': '0x0', 21:10:04 INFO - 'XPC_SERVICE_NAME': '0', 21:10:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:10:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:10:04 INFO - Downloading/unpacking blobuploader==1.2.4 21:10:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:10:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:10:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:10:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:10:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:10:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:10:07 INFO - Downloading blobuploader-1.2.4.tar.gz 21:10:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:10:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:10: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 21:10: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 21:10: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 21:10: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 21:10: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 21:10: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 21:10:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:10:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:10:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:10:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:10:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:10:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:10:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:10:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:10:08 INFO - Downloading docopt-0.6.1.tar.gz 21:10:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:10:08 INFO - Installing collected packages: blobuploader, requests, docopt 21:10:08 INFO - Running setup.py install for blobuploader 21:10:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:10:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:10:08 INFO - Running setup.py install for requests 21:10:09 INFO - Running setup.py install for docopt 21:10:09 INFO - Successfully installed blobuploader requests docopt 21:10:09 INFO - Cleaning up... 21:10:09 INFO - Return code: 0 21:10:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:10:09 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')]} 21:10:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:10:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:10:09 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')]} 21:10:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:10:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:10:09 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 0x106edfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106eef420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc4355d860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d7ee00>, '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 0x106be7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d79b20>, '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.qskAUs0bnP/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.VBofgv6Dtn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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 21:10:09 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 21:10:09 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 21:10:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qskAUs0bnP/Render', 21:10:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:10:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:10:09 INFO - 'HOME': '/Users/cltbld', 21:10:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:10:09 INFO - 'LOGNAME': 'cltbld', 21:10:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:10:09 INFO - 'MOZ_NO_REMOTE': '1', 21:10:09 INFO - 'NO_EM_RESTART': '1', 21:10:09 INFO - 'PAGER': '/bin/cat', 21:10:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:10:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:10:09 INFO - 'PWD': '/builds/slave/test', 21:10:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:10:09 INFO - 'SHELL': '/bin/bash', 21:10:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VBofgv6Dtn/Listeners', 21:10:09 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 21:10:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:10:09 INFO - 'USER': 'cltbld', 21:10:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:10:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:10:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:10:09 INFO - 'XPC_FLAGS': '0x0', 21:10:09 INFO - 'XPC_SERVICE_NAME': '0', 21:10:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:10:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:10:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:10:09 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-bcTx7b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:10:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:10:10 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-xWWUpo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:10:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:10:10 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-s0QrbO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:10:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:10:10 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-7wi_PX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:10:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:10:10 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-DKPdko-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:10:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:10:10 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-7aFk4G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:10:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:10:10 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-xtsGv0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:10:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:10:10 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-9kP02s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:10:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:10:10 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-4_zK7k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:10:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:10:10 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-Qg3Wl9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:10:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:10:10 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-XV_YU5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:10:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:10:11 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-KXTKcI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:10:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:10:11 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-A96_xl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:10:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:10:11 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-p5uwtN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:10:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:10:11 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-jVnvL5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:10:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:10:11 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-vz1HjF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:10:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:10:11 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-he97rL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:10:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:10:11 INFO - Running setup.py install for manifestparser 21:10:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:10:11 INFO - Running setup.py install for mozcrash 21:10:11 INFO - Running setup.py install for mozdebug 21:10:12 INFO - Running setup.py install for mozdevice 21:10:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:10:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:10:12 INFO - Running setup.py install for mozfile 21:10:12 INFO - Running setup.py install for mozhttpd 21:10:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:10:12 INFO - Running setup.py install for mozinfo 21:10:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:10:12 INFO - Running setup.py install for mozInstall 21:10:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:10:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:10:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:10:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:10:12 INFO - Running setup.py install for mozleak 21:10:12 INFO - Running setup.py install for mozlog 21:10:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:10:13 INFO - Running setup.py install for moznetwork 21:10:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:10:13 INFO - Running setup.py install for mozprocess 21:10:13 INFO - Running setup.py install for mozprofile 21:10:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:10:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:10:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:10:13 INFO - Running setup.py install for mozrunner 21:10:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:10:13 INFO - Running setup.py install for mozscreenshot 21:10:13 INFO - Running setup.py install for moztest 21:10:14 INFO - Running setup.py install for mozversion 21:10:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:10:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:10:14 INFO - Cleaning up... 21:10:14 INFO - Return code: 0 21:10:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:10:14 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')]} 21:10:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:10:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:10:14 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')]} 21:10:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:10:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:10:14 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 0x106edfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106eef420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc4355d860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d7ee00>, '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 0x106be7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d79b20>, '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.qskAUs0bnP/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.VBofgv6Dtn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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 21:10:14 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 21:10:14 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 21:10:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qskAUs0bnP/Render', 21:10:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:10:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:10:14 INFO - 'HOME': '/Users/cltbld', 21:10:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:10:14 INFO - 'LOGNAME': 'cltbld', 21:10:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:10:14 INFO - 'MOZ_NO_REMOTE': '1', 21:10:14 INFO - 'NO_EM_RESTART': '1', 21:10:14 INFO - 'PAGER': '/bin/cat', 21:10:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:10:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:10:14 INFO - 'PWD': '/builds/slave/test', 21:10:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:10:14 INFO - 'SHELL': '/bin/bash', 21:10:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VBofgv6Dtn/Listeners', 21:10:14 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 21:10:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:10:14 INFO - 'USER': 'cltbld', 21:10:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:10:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:10:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:10:14 INFO - 'XPC_FLAGS': '0x0', 21:10:14 INFO - 'XPC_SERVICE_NAME': '0', 21:10:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:10:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:10:14 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-CeHYue-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:10:14 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)) 21:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:10:14 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-eAS_gm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:10:14 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)) 21:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:10:14 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-j7fLHR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:10:14 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)) 21:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:10:14 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-F84aYp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:10:14 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)) 21:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:10:14 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-XWZMy3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:10:15 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)) 21:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:10:15 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-niRlKZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:10:15 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)) 21:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:10:15 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-1eA70r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:10:15 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)) 21:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:10:15 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-GmMDx3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:10:15 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)) 21:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:10:15 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-aU5GsK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:10:15 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)) 21:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:10:15 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-2oaBTJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:10:15 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)) 21:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:10:15 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-YeVhTS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:10:15 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)) 21:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:10:15 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-JWLiOq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:10:15 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)) 21:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:10:15 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-jM1Ekw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:10:15 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)) 21:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:10:15 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-rK8t1i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:10:15 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)) 21:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:10:15 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-kUBJub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:10:16 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)) 21:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:10:16 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-i20ebC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:10:16 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)) 21:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:10:16 INFO - Running setup.py (path:/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/pip-di_0MN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:10:16 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)) 21:10:16 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)) 21:10:16 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)) 21:10:16 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)) 21:10:16 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)) 21:10:16 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)) 21:10:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:10:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:10:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:10:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:10:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:10:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:10:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:10:18 INFO - Downloading blessings-1.6.tar.gz 21:10:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:10:18 INFO - Installing collected packages: blessings 21:10:18 INFO - Running setup.py install for blessings 21:10:18 INFO - Successfully installed blessings 21:10:18 INFO - Cleaning up... 21:10:19 INFO - Return code: 0 21:10:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:10:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:10:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:10:19 INFO - Reading from file tmpfile_stdout 21:10:19 INFO - Current package versions: 21:10:19 INFO - blessings == 1.6 21:10:19 INFO - blobuploader == 1.2.4 21:10:19 INFO - docopt == 0.6.1 21:10:19 INFO - manifestparser == 1.1 21:10:19 INFO - mozInstall == 1.12 21:10:19 INFO - mozcrash == 0.17 21:10:19 INFO - mozdebug == 0.1 21:10:19 INFO - mozdevice == 0.48 21:10:19 INFO - mozfile == 1.2 21:10:19 INFO - mozhttpd == 0.7 21:10:19 INFO - mozinfo == 0.9 21:10:19 INFO - mozleak == 0.1 21:10:19 INFO - mozlog == 3.1 21:10:19 INFO - moznetwork == 0.27 21:10:19 INFO - mozprocess == 0.22 21:10:19 INFO - mozprofile == 0.28 21:10:19 INFO - mozrunner == 6.11 21:10:19 INFO - mozscreenshot == 0.1 21:10:19 INFO - mozsystemmonitor == 0.0 21:10:19 INFO - moztest == 0.7 21:10:19 INFO - mozversion == 1.4 21:10:19 INFO - psutil == 3.1.1 21:10:19 INFO - requests == 1.2.3 21:10:19 INFO - wsgiref == 0.1.2 21:10:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:10: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')]} 21:10:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:10:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:10: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')]} 21:10:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:10:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:10:19 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 0x106edfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106eef420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc4355d860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d7ee00>, '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 0x106be7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d79b20>, '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.qskAUs0bnP/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.VBofgv6Dtn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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 21:10:19 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 21:10:19 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 21:10:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qskAUs0bnP/Render', 21:10:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:10:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:10:19 INFO - 'HOME': '/Users/cltbld', 21:10:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:10:19 INFO - 'LOGNAME': 'cltbld', 21:10:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:10:19 INFO - 'MOZ_NO_REMOTE': '1', 21:10:19 INFO - 'NO_EM_RESTART': '1', 21:10:19 INFO - 'PAGER': '/bin/cat', 21:10:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:10:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:10:19 INFO - 'PWD': '/builds/slave/test', 21:10:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:10:19 INFO - 'SHELL': '/bin/bash', 21:10:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VBofgv6Dtn/Listeners', 21:10:19 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 21:10:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:10:19 INFO - 'USER': 'cltbld', 21:10:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:10:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:10:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:10:19 INFO - 'XPC_FLAGS': '0x0', 21:10:19 INFO - 'XPC_SERVICE_NAME': '0', 21:10:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:10:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:10:19 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)) 21:10:19 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)) 21:10:19 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)) 21:10:19 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)) 21:10:19 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)) 21:10:19 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)) 21:10:19 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)) 21:10:19 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)) 21:10:19 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)) 21:10:19 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)) 21:10:19 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)) 21:10:19 INFO - Cleaning up... 21:10:19 INFO - Return code: 0 21:10:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:10: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')]} 21:10:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:10:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:10: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')]} 21:10:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:10:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:10:19 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 0x106edfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106eef420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc4355d860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d7ee00>, '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 0x106be7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d79b20>, '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.qskAUs0bnP/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.VBofgv6Dtn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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 21:10:19 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 21:10:19 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 21:10:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qskAUs0bnP/Render', 21:10:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:10:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:10:19 INFO - 'HOME': '/Users/cltbld', 21:10:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:10:19 INFO - 'LOGNAME': 'cltbld', 21:10:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:10:19 INFO - 'MOZ_NO_REMOTE': '1', 21:10:19 INFO - 'NO_EM_RESTART': '1', 21:10:19 INFO - 'PAGER': '/bin/cat', 21:10:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:10:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:10:19 INFO - 'PWD': '/builds/slave/test', 21:10:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:10:19 INFO - 'SHELL': '/bin/bash', 21:10:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VBofgv6Dtn/Listeners', 21:10:19 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 21:10:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:10:19 INFO - 'USER': 'cltbld', 21:10:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:10:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:10:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:10:19 INFO - 'XPC_FLAGS': '0x0', 21:10:19 INFO - 'XPC_SERVICE_NAME': '0', 21:10:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:10:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:10:19 INFO - Downloading/unpacking jsonschema 21:10:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:10:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:10:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:10:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:10:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:10:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:10:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:10:22 INFO - Downloading/unpacking functools32 (from jsonschema) 21:10:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:10:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:10:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:10:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:10:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:10:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:10:22 INFO - Downloading functools32-3.2.3-2.tar.gz 21:10:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:10:22 INFO - warning: no files found matching '*.txt' 21:10:22 INFO - no previously-included directories found matching 'build' 21:10:22 INFO - no previously-included directories found matching 'dist' 21:10:22 INFO - no previously-included directories found matching '.git*' 21:10:22 INFO - Installing collected packages: jsonschema, functools32 21:10:22 INFO - Running setup.py install for functools32 21:10:22 INFO - warning: no files found matching '*.txt' 21:10:22 INFO - no previously-included directories found matching 'build' 21:10:22 INFO - no previously-included directories found matching 'dist' 21:10:22 INFO - no previously-included directories found matching '.git*' 21:10:22 INFO - Successfully installed jsonschema functools32 21:10:22 INFO - Cleaning up... 21:10:23 INFO - Return code: 0 21:10:23 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 21:10:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:10:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:10:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:10:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:10:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:10:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:10:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106edfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106eef420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc4355d860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106d7ee00>, '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 0x106be7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d79b20>, '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.qskAUs0bnP/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.VBofgv6Dtn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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 21:10:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 21:10:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 21:10:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qskAUs0bnP/Render', 21:10:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:10:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:10:23 INFO - 'HOME': '/Users/cltbld', 21:10:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:10:23 INFO - 'LOGNAME': 'cltbld', 21:10:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:10:23 INFO - 'MOZ_NO_REMOTE': '1', 21:10:23 INFO - 'NO_EM_RESTART': '1', 21:10:23 INFO - 'PAGER': '/bin/cat', 21:10:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:10:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:10:23 INFO - 'PWD': '/builds/slave/test', 21:10:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:10:23 INFO - 'SHELL': '/bin/bash', 21:10:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VBofgv6Dtn/Listeners', 21:10:23 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 21:10:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:10:23 INFO - 'USER': 'cltbld', 21:10:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:10:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:10:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:10:23 INFO - 'XPC_FLAGS': '0x0', 21:10:23 INFO - 'XPC_SERVICE_NAME': '0', 21:10:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:10:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:10:23 INFO - Downloading/unpacking flake8 21:10:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:10:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:10:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:10:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:10:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:10:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:10:25 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 21:10:25 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 21:10:25 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 21:10:25 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 21:10:25 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 21:10:25 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 21:10:25 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 21:10:25 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 21:10:26 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 21:10:26 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 21:10: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 21:10: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 21:10: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 21:10: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 21:10: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 21:10: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 21:10:26 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 21:10: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 21:10: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 21:10: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 21:10: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 21:10: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 21:10: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 21:10:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 21:10:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:10:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:10:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:10:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:10:27 INFO - no previously-included directories found matching 'docs/_build' 21:10:27 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 21:10:27 INFO - Running setup.py install for pep8 21:10:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:10:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:10:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:10:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:10:27 INFO - no previously-included directories found matching 'docs/_build' 21:10:27 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 21:10:27 INFO - Successfully installed flake8 mccabe pyflakes pep8 21:10:27 INFO - Cleaning up... 21:10:27 INFO - Return code: 0 21:10:27 INFO - Running post-action listener: _resource_record_post_action 21:10:27 INFO - Running post-action listener: _start_resource_monitoring 21:10:27 INFO - Starting resource monitoring. 21:10:27 INFO - ##### 21:10:27 INFO - ##### Running install step. 21:10:27 INFO - ##### 21:10:27 INFO - Running pre-action listener: _resource_record_pre_action 21:10:27 INFO - Running main action method: install 21:10:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:10:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:10:27 INFO - Reading from file tmpfile_stdout 21:10:27 INFO - Detecting whether we're running mozinstall >=1.0... 21:10:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:10:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:10:27 INFO - Reading from file tmpfile_stdout 21:10:27 INFO - Output received: 21:10:27 INFO - Usage: mozinstall [options] installer 21:10:27 INFO - Options: 21:10:27 INFO - -h, --help show this help message and exit 21:10:27 INFO - -d DEST, --destination=DEST 21:10:27 INFO - Directory to install application into. [default: 21:10:27 INFO - "/builds/slave/test"] 21:10:27 INFO - --app=APP Application being installed. [default: firefox] 21:10:27 INFO - mkdir: /builds/slave/test/build/application 21:10:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 21:10:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 21:10:59 INFO - Reading from file tmpfile_stdout 21:10:59 INFO - Output received: 21:10:59 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 21:10:59 INFO - Running post-action listener: _resource_record_post_action 21:10:59 INFO - ##### 21:10:59 INFO - ##### Running run-tests step. 21:10:59 INFO - ##### 21:10:59 INFO - Running pre-action listener: _resource_record_pre_action 21:10:59 INFO - Running main action method: run_tests 21:10:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:10:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:10:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:10:59 INFO - 2016-05-19 21:10:59.672 screenresolution[1640:7923] starting screenresolution argv=screenresolution get 21:10:59 INFO - 2016-05-19 21:10:59.675 screenresolution[1640:7923] Display 0: 1600x1200x32@60 21:10:59 INFO - 2016-05-19 21:10:59.681 screenresolution[1641:7926] starting screenresolution argv=screenresolution list 21:10:59 INFO - Available Modes on Display 0 21:10:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:10:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:10:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:10:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:10:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:10:59 INFO - Intel Iris: 21:10:59 INFO - Chipset Model: Intel Iris 21:10:59 INFO - Type: GPU 21:10:59 INFO - Bus: Built-In 21:10:59 INFO - VRAM (Dynamic, Max): 1536 MB 21:10:59 INFO - Vendor: Intel (0x8086) 21:10:59 INFO - Device ID: 0x0a2e 21:10:59 INFO - Revision ID: 0x0009 21:10:59 INFO - Displays: 21:10:59 INFO - Display: 21:10:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:10:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:10:59 INFO - Display Serial Number: 1600x1200 60 21:10:59 INFO - Main Display: Yes 21:10:59 INFO - Mirror: Off 21:10:59 INFO - Online: Yes 21:10:59 INFO - Rotation: Supported 21:10:59 INFO - Return code: 0 21:10:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:10:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:10:59 INFO - Python 2.7.3 21:10:59 INFO - Return code: 0 21:10:59 INFO - grabbing minidump binary from tooltool 21:10:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:10:59 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 0x106d7ee00>, '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 0x106be7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106d79b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:10:59 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 21:10:59 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 21:10:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:10:59 INFO - Return code: 0 21:10:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:11:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:11:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:11:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:11:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:11:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 21:11:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 21:11:01 INFO - Return code: 0 21:11:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/L-vWqz3XQH2ICR74QtoRog/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0144', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 21:11:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/L-vWqz3XQH2ICR74QtoRog/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0144 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 21:11:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qskAUs0bnP/Render', 21:11:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:11:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:11:01 INFO - 'HOME': '/Users/cltbld', 21:11:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:11:01 INFO - 'LOGNAME': 'cltbld', 21:11:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:11:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:11:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:11:01 INFO - 'MOZ_NO_REMOTE': '1', 21:11:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:11:01 INFO - 'NO_EM_RESTART': '1', 21:11:01 INFO - 'PAGER': '/bin/cat', 21:11:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:11:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:11:01 INFO - 'PWD': '/builds/slave/test', 21:11:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:11:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:11:01 INFO - 'SHELL': '/bin/bash', 21:11:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VBofgv6Dtn/Listeners', 21:11:01 INFO - 'TMPDIR': '/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/', 21:11:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:11:01 INFO - 'USER': 'cltbld', 21:11:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:11:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:11:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:11:01 INFO - 'XPC_FLAGS': '0x0', 21:11:01 INFO - 'XPC_SERVICE_NAME': '0', 21:11:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:11:01 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/L-vWqz3XQH2ICR74QtoRog/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0144', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 21:11:01 INFO - mozversion application_buildid: 20160519191805 21:11:01 INFO - mozversion application_changeset: 9ba18aae69e626a3f83560df321d91e42316083a 21:11:01 INFO - mozversion application_display_name: FirefoxDeveloperEdition 21:11:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:11:01 INFO - mozversion application_name: Firefox 21:11:01 INFO - mozversion application_remotingname: firefox-dev 21:11:01 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 21:11:01 INFO - mozversion application_vendor: Mozilla 21:11:01 INFO - mozversion application_version: 48.0a2 21:11:01 INFO - mozversion platform_buildid: 20160519191805 21:11:01 INFO - mozversion platform_changeset: 9ba18aae69e626a3f83560df321d91e42316083a 21:11:01 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 21:11:01 INFO - mozversion platform_version: 48.0a2 21:11:01 INFO - using testdate: 1463717461 21:11:01 INFO - actual date: 1463717461 21:11:01 INFO - starting webserver on 'localhost:49273' 21:11:01 INFO - SUITE-START | Running 2 tests 21:11:01 INFO - TEST-START | dromaeo_css 21:11:01 INFO - operating with platform_type : mac_ 21:11:01 INFO - Initialising browser for dromaeo_css test... 21:11:01 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/tmpg47Ayj/profile http://localhost:49273/getInfo.html) 21:11:03 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 21:11:03 INFO - PROCESS | 1659 | colorDepth:24 21:11:03 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 21:11:03 INFO - PROCESS | 1659 | __metrics 21:11:03 INFO - PROCESS | 1659 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 21:11:03 ERROR - PROCESS | 1659 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 21:11:04 INFO - TEST-INFO | 1659: exit 0 21:11:04 INFO - Browser initialized. 21:11:04 INFO - Running cycle 1/1 for dromaeo_css test... 21:11:04 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/tmpg47Ayj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:11:16 INFO - PROCESS | 1662 | RSS: Main: 211427328 21:11:16 INFO - PROCESS | 1662 | 21:12:08 INFO - PROCESS | 1662 | 1463717528043 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] 21:14:18 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49273/tests/dromaeo/cssquery-ext.html) 21:14:20 INFO - PROCESS | 1662 | RSS: Main: 419262464 21:14:20 INFO - PROCESS | 1662 | 21:17:23 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-ext.html (next: http://localhost:49273/tests/dromaeo/cssquery-jquery.html) 21:17:23 INFO - PROCESS | 1662 | RSS: Main: 360890368 21:17:23 INFO - PROCESS | 1662 | 21:20:26 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49273/tests/dromaeo/cssquery-mootools.html) 21:20:26 INFO - PROCESS | 1662 | RSS: Main: 360882176 21:20:26 INFO - PROCESS | 1662 | 21:23:28 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49273/tests/dromaeo/cssquery-prototype.html) 21:23:29 INFO - PROCESS | 1662 | RSS: Main: 359567360 21:23:29 INFO - PROCESS | 1662 | 21:26:32 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49273/tests/dromaeo/cssquery-yui.html) 21:26:33 INFO - PROCESS | 1662 | RSS: Main: 327094272 21:26:33 INFO - PROCESS | 1662 | 21:29:36 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-yui.html (next: http://localhost:49273/tests/dromaeo/cssquery-dojo.html) 21:29:36 INFO - PROCESS | 1662 | RSS: Main: 369094656 21:29:36 INFO - PROCESS | 1662 | 21:29:36 INFO - PROCESS | 1662 | __start_tp_report 21:29:36 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 21:29:36 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 21:29:36 INFO - PROCESS | 1662 | |i|pagename|runs| 21:29:36 INFO - PROCESS | 1662 | |0;dojo.html;3333.1167240681225;3391.304782519582;3407.693307602316;3446.517487551742;3470.132466883279;5615.578831587637;5632.549396048316;5635.830925072244;5659.066254068079;5663.858403539908;5622.240997465341;5675.971620141904;5680.772769089232;5691.743871525781;5699.344575373833;5039.470855560168;5080.644354895159;5130.281760553526;5162.845114646567;5204;5196.844094677166;5197.896042079156;5198.7660555274915;5209.687418754868;5212.061828870802;5102.1836506159025;5167.147420675584;5170.793168273264;5180.300659410969;5202.479752024805;5183.352080989857;5204.453532379083;5213.843584692465;5214.921776173361;5261.631685781997;5017.197248440232;5055.34280543527;5071.087204303224;5151.871203219912;5154.793808247703;5530.336359636869;5559.8888022239735;5576.470235327637;5584.1902924076185;5627.5779316551425;6143.047827586686;6173.32093469718;6182.96908515459;6185.041318595625;6186.690665466709;6090.23872015998;6150.954337762591;6185.597936134137;6260.186175797163;6275.843103922439;5684.8863022739315;5735.455131762559;5737.082066869364;5746.166805813175;5776.249087618625;6224.564280500322;6228.968855155696;6243.406876346739;6247.125402443661;6263.997760358412;5924.377940316291;6031.8793624127275;6041.78853740117;6095.116208149793;6096.207493025967;6197.194364732556;6215.502759779207;6216.906746398808;6231.127642130105;6254.124422580842;6076.301225359052;6122.877542449126;6135.9079613805825;6139.2325959255095;6172.074188871705;6050.6974651267265;6115.240341084353;6116.1836049259855;6190.350013248634;6202.782902598387;4805.591524720368;4842.878928026757;4846.975765121222;4851.199552073939;4857.514248575114;4369.126174764996;4401.317795741666;4405.163019026375;4442.444694413199;4486.237339652267;5620.662760234317;5634.52106570946;5636.154576813429;5655.1517272409465;5655.293088363955;6020.57855950088;6181.598196117238;6244.219472565929;6247.5001999840815;6266.310705822356;5595.300587426572;5614.073677843192;5617.297837770278;5617.325920889438;5651.519620832193;5289.2859463972645;5309.150535914235;5314.867128321823;5317.361916569959;5323.920141197885;6199.938000620026;6219.626822390671;6267.059941008885;6270.404311590483;6272.153259310026;6262.060690896402;6269.811905642838;6270.49836013127;6273.9372606273355;6280.183576135073;6253.624782512882;6260.655663938527;6261.655608941552;6266;6266.404691554295;4516.593506584291;5934.703264836655;5948.048312270016;5951.672658003678;5980.671063091398;6023.156758053964;6146.2175968968495;6147.538934580014;6176.567640265138;6181.443670069715;6028.186194863637;6035.36628653985;6058.636481811105;6071.514278857592;6074.42292982166;5994.400559943971;6041.78853740117;6059.787907423219;6061.3029501606115;6069.02895536712;5717.714114294352;5782.2483077199695;5824.504917082096;5830.5044071254415;5846.1815345852465;5945.702714864326;5965.373635768182;5986.4013598639785;5989.131575921554;6078.817635470943;5841.2406387171095;5875.059990401515;5911.408859114054;5919.171316015675;5943.64338139713 21:29:36 INFO - PROCESS | 1662 | |1;ext.html;524.326240780597;556.568659289051;560.6103757888269;584.400988986289;587.6630665236589;30161.849190754045;30274.84862575687;37342;38690.419643705296;38741.61258387415;42014;42304;42362.364564531556;42365.36451953223;42560.57439425605;8507.276881465083;9462.952685236582;9696.030396960317;9701.75745606362;9703.466309352983;8637.136286371344;8746.518941458216;8750.562471876412;8778.824423511525;11563.364014979215;10633.521491532862;10907.454627268644;11453.255538389978;11559.306441613488;11574.24767390121;11424;11528.077753779677;11530.942345288304;11539.653810385702;11540.076793856471;8602.827943441158;11215.831762523569;11347.432628368548;11666.416679166008;11711.121665875009;38847.41728874069;39134.60865391338;39208.80395598032;39218.60781392178;39247.60752392468;3291.358185153896;3291.753118516121;3297.5383446317533;3322.767406281537;3377.1219482934366;26707.866460667767;27393.589096163574;27871.721282787115;28247.858760706065;28473.145805625863;2713.81003329767;2749.752522273005;2762.1575419497044;2842.6873043965147;2850.686424493304;488018;490650;491953;492300;493328.5333573309;63238.68380658159;64733.02900456497;64851.757412129205;64882.70234595281;64899;67199;67462.66268668625;67481.66259168673;67525.66237168782;67739.66130169416;17063.744043839353;17503.64992700139;17598.648027039388;17606.735898961524;17661.911690441724;13626.795598066037;13714.51999180024;13722.45110195601;13735.931320343334;13763.31183440844;14566.344514496874;15516.29320774714;15850;17759.378421755177;17812.10939453022;6979.30206979298;7367.042284503087;7493.175750667531;7554.169041405441;7574.545527268271;889.2885691446817;983.4836710726926;1006.3609607899036;1022.8465730140539;1067.3062509368972;27780.58329125065;27860.582091268647;30094;30445.69554304473;30719;1077.2782235901964;1096.9341839489657;1119.2333251722596;1175.7060734816296;1228.7112528555783;15302.770458443132;15649.921750391402;15680.215989200495;15742.646767744709;15871.603209919613;601284;609364;610301;610461;611915;61589.384106158366;61870;61947.38052619506;61990.69004654948;62004.68997654983;1352.8038434426953;1480.4670318685264;1501.96996060076;1516.2343016776458;1538.3769573322822;24874.626880597167;29945.70054299429;30243.72727680709;30306.393872122873;30319.848400757855;53814.73092634512;54632.45367546273;54649.72675136599;54718.726406367714;54723.45276547343;552050;554957;567817;569228;569728;35460.64539354573;41674.58325416828;43577.346339804295;44214.778926105166;44813.551864480934;44473.33290000588;44934.77532612316;45033.32450013318;46191.76904115458;46195.76902115468;36271.91184264476;36897.63102368942;37472.81263593664;37573.62426375701;38358.80820595991;36657.45013824741;36791.81604091962;36798.44802327914;37171.81414092912;37593.81203093967;36620.63379366279;37645.24709505849;37919.05202369963;39083;39182;33473;33558.16104597307;34052.65947340594;34245.486317705756;34325.65674343224;25274.620880687173;25409.110681126072;25596.48807023812;25603.743962560882;25845 21:29:36 INFO - PROCESS | 1662 | |2;jquery.html;355.9056849934767;399.24917319667514;399.8140864498006;403.56213508343444;407.42553000269623;15472.922635386836;16647;19775.5056123597;20413.591728165447;20424.489387765312;15702.76445853313;16043.278052487636;16864.988100713934;18178.4546463606;20162.395128146178;5612.07400778871;5681.545476361881;5911.261092363455;5913;5920.5559583031445;5782.9421705782825;5892.322382925954;5893.941060589404;5897.616654917438;5913;4141.7100802943805;5530.806421775239;6433.292337842833;6437.130987316699;6453.774117905874;6167.290761562433;6402.167718196652;6415.230172379321;6417.0695249189075;6417.647029413403;5594.692291923942;5611.354694210137;5615.241942337773;5624.29696287964;5645;25685.871570642026;25692.48615027689;25775;26045.265247149156;26089.608655870175;2078.8233000195;2303.2284184798114;2356.4580146566386;2653.1775149703653;2820.365417780983;14068.71862562743;14823.407063717439;14890.627734306729;15100.622484437867;15101.924490377478;1433.655922578584;1784.4914199453094;1791.0507431061558;1815.818418158187;1893.8958357290344;103755.44366834503;113412.29881551783;113548;113615;118695.81304186933;10451.320664156805;10721.571137154582;10972.780544389068;10990.945045274722;11104.666859994213;13808.654783630289;13887.305634718427;13892.305384730724;13906.4437422504;13910;7143.607101609461;7606.885896711554;8600.354973376896;8663.306935445149;8763.430377025474;8876.8668469973;8890.822183556422;8900.777480562909;8994.010658827523;8997.550122493849;6444.033394990695;6615.17310336208;6621.602703837785;6639.834004149935;6656.434203092695;1686.8566171875282;1834.7339635752742;1860.8555738221166;1869.1962456143988;1879.5207222158263;914.1133100892257;929.5538141691891;979.4221409368507;997.0826839307854;1066.0842595501297;19090.904545477184;19728.816271023497;20600.896995514926;21049.473763156042;21111.8944405277;873.8421591391304;1007.7379881230933;1014.5789497358513;1019.0522813783252;1044.5717255925035;10145.543450544743;10532.36805791655;10962.451877406258;11205.831912521318;11232.831507527393;597180;614921;616739;628909;631777;112426;114594.42702786432;115693.42153289179;117293;119020;1027.732789474727;1038.7299302181486;1041.2034793383068;1042.3954106618144;1071.0307172009343;17932.731009035135;17935.73096403529;18107.81892181114;18227.817721823143;18878.3392581259;49998.75003124951;50811.72970675763;51473.29098246017;52625.15811946563;53034.40896773103;67505.66247168733;70860.29139708537;71020.57958840897;72616.6369168172;74014.62992685002;7825.100113487022;7864.2922137007945;7896.605169741375;7916.920830791619;8183.222593853575;11882.702932426413;12343.259404435763;12363.196392234477;12408.069394795246;12708.110432269652;10641.467926603484;10761.784764304826;11030.50362733695;11084.113270938467;11090.334579925231;9777.973312802053;10269.84595231087;10703.571857125628;10776.515056822305;11053.83419248728;10852.728681783019;10963.177761668067;11343.943280283545;11437.370944598128;11475.024622907147;8123.252077672242;8133.593320333841;8197.016358036835;8264.173582641928;8462.534560599226;9974.551145198338;10990.340579565252;11192.944035280098;11212.55149793999;11415.486303116546 21:29:36 INFO - PROCESS | 1662 | |3;mootools.html;1355.6272025193073;1406.0790182430499;1568.059164501299;1750.5273576134443;1932.091916799104;8002.8799568006525;8669.436486628365;8802.163794439533;8808.163224493663;8821.735347939564;8478.406511544194;8715.651373945035;8725.5637218139;8733.65065397385;8739.956300218506;2435.1598698449006;2466.987665061672;2469.444375015625;2470.22188010776;2471.1968610201666;2390.2231774673214;2453.251758213744;2453.717822450414;2455.6193789962495;2468.0744720729726;2429.866357350345;2458.6680798092298;2472;2475.207933461293;2484.6024636058232;2429.161939131006;2460.2988148377626;2465.6424818401233;2466.3094333586623;2468.827182097254;2409.168836751318;2446.755324467557;2447.3514518652573;2459.1884678056376;2460.5570997220493;8592.355573332025;8837.911620883773;8876;8886.644534218623;8956.507392093432;5332.440093790135;5501.834944951658;5537.390887002426;5539.473749993744;5558.416366281523;3534.805585692786;3602.2975519773654;3653.799041052741;3674.3018826422895;3694.519712437366;1047.701405099543;1062.4262898035051;1066.648006157966;1073.2540884085563;1100.9614663486739;11898.405079746064;11968.581099661556;12110.212836165621;12117.151799374047;12131.696707582292;4529.230030894756;4569.520200378979;4666.626669866402;4688.531146885284;4712.340272361872;9410.388324759006;9421;9434.90565094354;9445.76385590352;9445.905540944503;5354.089804733127;5356.65181763192;5374.193870919394;5392.838214853561;5405.13517837152;4434.600885920218;4540.659450541157;4788.377510923558;4947.925781113287;5015.523525265094;3835.9041023974623;3849.576546579874;3903.785291808921;3932.744371615893;3959.1883663848844;1211.1400905357118;1216.1730023584048;1218.2264262193394;1227.6194379742308;1230.0565107130712;673.5251647588439;786.7049856303886;908.8863892013499;948.0519480519481;959.0217977662884;5646;5741.913871291934;5828.154917536934;5949.613275137102;5979.103134529768;486.88071422501713;504.8990201959623;511.15659162382366;511.3480312601463;665.8048802399704;3553.5202747629255;3571.0715214044535;3849.1339448623835;3900.258950799339;3960.346542820402;10584;10908.290961087503;10908.836367454494;11031.558737650565;11033;10606.257561970588;10742.570297188026;10891;10898.291611045257;10988.395638239974;8336.583170841555;8353.582320884054;8379.162083791694;8417.621207045697;8468.364872634576;5495.752691128829;5771.422857714195;5826.446487583673;5854.736536855937;5920.023196172756;9712.271579631415;9726.027397260217;9733.124018838338;9859.30984831055;9896;9420.670276540288;9929.503524823585;9943.651972180938;9944.005599439997;9950;7012.964935175291;7059.82350441243;7142.214356420685;7147.606881621561;7168.498205125591;8081.4747041442115;8336.249737523653;8343.207395297437;8359.205875441823;8371;6389.552731308764;6976.7558135465015;7323.780286591411;7466.216047314953;7637;7202.027726256888;7221.602811845399;7267.382272506896;7350.77947661571;7370.078740157481;6861.519693621399;6887.173539175331;6920.273371295942;6929.618870961944;6944.375006249462;4742.502037286174;4755.762211889322;4849.927251091166;4891.413030436371;4932.383452068491;4930.852074437773;4966.428860680923;4971.229459433794;5057.266696329085;5164.715940623302 21:29:36 INFO - PROCESS | 1662 | |4;prototype.html;319.56060416926726;322.25558958805163;327.8737685990894;330.24044697196456;334.0829423233225;6327.715252813623;6383.457406120479;6388.425041746248;6436.452901503366;6436.485081193505;6322.146510221119;6324.715387807548;6332.841678958017;6354.68226588671;6368.458681012119;4490.214212512814;4557.08858228354;4629.328747331635;4665.813367465297;4700.318453824193;3895;4597.218472859622;4681.016986432854;4683.742394168319;4691.812327506896;4614.953850461503;4615;4636.953630463703;4637.8840528986875;4640.582347588716;4450.57719516647;4506.909861802778;4611.2852507861335;4664.836730714409;4692.460367057798;4038.172174704179;4041.7777022263763;4051.493563304587;4055.9112057290517;4058.431819545266;6043.7280322385595;6084.695765211722;6111.297200821873;6232.1898153240245;6233.688315584202;5375.978564072813;5479.2602998595075;5484.2596249506605;5494.862628434281;5503.064479038573;5663.433656634304;6042.546808989343;6154.799814036265;6683.832904177434;6705.2624211336715;4164.68764842638;4383.649308055348;4421.955780442187;4440.312681181163;4506.8873278167985;6102.816915492543;7078.964605176992;7125.679344429462;7149.821254468628;7158.534695244793;3762.492063571383;4496.258117410656;4500.594946454769;4504.684672072989;4505.098980203972;4554;4554.795034223468;4558.817647294071;4569.2689169732685;4583.495815460296;5409.26974858397;5411.702356370359;5425.945740542623;5440.755166017539;5445.264889239981;5786;6534.379233972765;6730.091437655951;6808.489363297773;6829;1410.8941829362839;1559.8752099831986;1579.3840402243134;1583.1371902313037;1583.9841601584067;861.9439736417113;952.0958083832336;957.7262241219162;997.3666721631671;1016.009390843936;6123.601965872204;6123.755049798047;6130.754769809158;6234.973633569108;6422.74309027643;7114.253003434668;7119.5728256303755;7135.215126336099;7135.500514963903;7139.857202855914;6404.391582799627;6411.198600174978;6436.935630643634;6438.098666186737;6444.355564443518;3684.8157592120288;3734.1971476132762;3736.4021756518828;3739.270842185748;3753.099256178552;6171.783987560414;7041.260667629927;7296.306850849161;7336.96331518339;7397.149327827261;7255.746048888263;7265.745698900513;7266.709331626781;7286.562806231643;7326.3406293433845;7372.557646541225;7374.446916481285;7379.6679149438405;7385;7389.926100738924;6795.38841504267;7069.752558660422;7082.716691332495;7087.255838136922;7097.964510177416;6895.896561551681;6926.411255043377;6934.930650693428;6949;6966.268541803038;5975.910361344497;7317.487775855639;7349.55902645843;7367.926320736724;7369.078865141857;4479.417675702138;4482.775861206992;4494.101179764126;4500.212462819059;4502.009557897389;5773.74018169175;6963.990221417967;7050.612216328152;7054.082969214174;7055.682494287872;5446.836594902159;6033.306169790355;6804.319568043156;6883.621400823003;6889.345512176335;6823.112995310578;6862.176538815374;6862.210845752603;6869.416099631588;6874.175098988154;6675.7997260082275;6688.464922806066;6701.698423571049;6704.597724136567;6730.293319201609;6864.313568643096;6873.1064961554675;6898.620575868375;6898.689558969758;6923.2038315594355;6436.485081193399;6466.741330346734;6584.176977877765;6654.3012983637955;6692.498062645223 21:29:36 INFO - PROCESS | 1662 | |5;yui.html;668.0547025958269;691.7475121580624;700.1912790726713;711.3029231353275;755.9584222867744;15074;15189.164595947232;15363.999999999985;17131.828681713178;17841.821581784177;16087.678246435093;16135.43525976591;16211.432599859007;16227.94518356306;16291.755623665626;728.5155371677854;744.9962750186241;921.9078092190761;943.5329511891623;947.9763005924838;914.0813482450128;922.0502882031518;922.6125027488438;923.1368670293264;923.7367350305165;972.9464879431629;973.4548652754445;975.4293738163183;975.790205105902;980.6616717232544;763.1567118334216;960.851068084448;971.8153550825356;974.5565767575736;981.7594689301131;723.5260904107818;724.6231959381092;725.4269127389356;728.83236855523;735.1141874041767;15230.086194828345;15297.847021529753;15316.846831531653;15335.693286134216;15409.152496612682;12241.571544995972;12327.938360308231;12428.75142497145;12545.31000794956;12561.371931403393;10057;10110.797784044351;10180.694579162637;10185.08334249921;10288.971102889725;3450.4824276358745;3462.169079420946;3488.5988111367265;3549.5917969433585;3712.7586706864236;80422;81573.59213203956;82084.76872846912;82156.58921705413;82388;9351.111644393772;9424.293178011676;9602.039796020481;9610.327277090677;10146.695599132037;20999.685004724943;21028.894855525625;21045.790192029617;21411.571768564543;21458.892705536684;7355.448341374418;7377.372923301472;7569.5079819811535;7591.506552074097;7731.149573546905;4089.795510224477;4123.752574845519;4188.769617670996;4391.956080439219;4411.117776444724;3398;3516.24400753834;3529.505869178317;3532.9646703532635;3584;632.8576070384127;650.9707063182168;655.2857385449882;660.8645227728305;661.2197606823897;519.4104691175543;607.3167686352853;614.9108379284978;625.9765431854585;636.096742625473;7671.6164191790185;7833.64748586315;7878.763637090897;8469.280111190494;8809;540.7701726766109;586.7946218823378;613.0191693290699;620.1028221612497;626.674129452682;3699.963000370016;3812.390017597171;3869.2454971280895;3898.8050597470015;3931.8623848165175;88033.11966880377;89562.10437895668;90716.5464172675;91020;91099.08900911038;58383.70808145932;72333.638331808;74069.6296518514;74166;76179;70070;70283;70426.64786676034;70475;71427;6701.597904125768;7644.770656880302;7790.948221989959;7917.802054948673;8024.037115546172;47959.760201200166;47972.04055918932;48779.75610121927;49195;49420.50579494303;64692;65056.72301469426;66856.66571667306;66862.33137668562;68331.65834170996;9973.900260997298;10001.899981000097;10094.091531762177;10181.185505159421;10624.043836054792;10862.674119776419;10883.129349652167;11135.220534562503;11325.490352933974;11982.161248712506;10182.338148020355;10486.632967846088;10651.360918344924;10708.571657133627;10732.73168170802;10478.266521343738;10601.946990265;10742.62400815999;10778.407187604756;10982.725431864268;10213.723284589427;10260.948695256477;10262.973702629677;10412.27114102036;11033.83449248278;8688.348373872112;8962.596683149144;9143.588538515649;9209.447433154031;9296.163345298955;8899.421537600036;8916.821663566563;8957.910420895707;9008.324375671982;9080.137386948229 21:29:36 INFO - PROCESS | 1662 | __end_tp_report 21:29:36 INFO - PROCESS | 1662 | __start_cc_report 21:29:36 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,2041 21:29:36 INFO - PROCESS | 1662 | __end_cc_report 21:29:36 INFO - PROCESS | 1662 | __startTimestamp1463718576446__endTimestamp 21:29:36 INFO - PROCESS | 1662 | 21:29:36 INFO - PROCESS | 1662 | ------- Summary: start ------- 21:29:36 INFO - PROCESS | 1662 | Number of tests: 6 21:29:36 INFO - PROCESS | 1662 | 21:29:36 INFO - PROCESS | 1662 | [#0] dojo.html Cycles:165 Average:5677.63 Median:5875.06 stddev:627.40 (10.7%) stddev-sans-first:601.76 21:29:36 INFO - PROCESS | 1662 | Values: 3333.1 3391.3 3407.7 3446.5 3470.1 5615.6 5632.5 5635.8 5659.1 5663.9 5622.2 5676.0 5680.8 5691.7 5699.3 5039.5 5080.6 5130.3 5162.8 5204.0 5196.8 5197.9 5198.8 5209.7 5212.1 5102.2 5167.1 5170.8 5180.3 5202.5 5183.4 5204.5 5213.8 5214.9 5261.6 5017.2 5055.3 5071.1 5151.9 5154.8 5530.3 5559.9 5576.5 5584.2 5627.6 6143.0 6173.3 6183.0 6185.0 6186.7 6090.2 6151.0 6185.6 6260.2 6275.8 5684.9 5735.5 5737.1 5746.2 5776.2 6224.6 6229.0 6243.4 6247.1 6264.0 5924.4 6031.9 6041.8 6095.1 6096.2 6197.2 6215.5 6216.9 6231.1 6254.1 6076.3 6122.9 6135.9 6139.2 6172.1 6050.7 6115.2 6116.2 6190.4 6202.8 4805.6 4842.9 4847.0 4851.2 4857.5 4369.1 4401.3 4405.2 4442.4 4486.2 5620.7 5634.5 5636.2 5655.2 5655.3 6020.6 6181.6 6244.2 6247.5 6266.3 5595.3 5614.1 5617.3 5617.3 5651.5 5289.3 5309.2 5314.9 5317.4 5323.9 6199.9 6219.6 6267.1 6270.4 6272.2 6262.1 6269.8 6270.5 6273.9 6280.2 6253.6 6260.7 6261.7 6266.0 6266.4 4516.6 5934.7 5948.0 5951.7 5980.7 6023.2 6146.2 6147.5 6176.6 6181.4 6028.2 6035.4 6058.6 6071.5 6074.4 5994.4 6041.8 6059.8 6061.3 6069.0 5717.7 5782.2 5824.5 5830.5 5846.2 5945.7 5965.4 5986.4 5989.1 6078.8 5841.2 5875.1 5911.4 5919.2 5943.6 21:29:36 INFO - PROCESS | 1662 | 21:29:36 INFO - PROCESS | 1662 | [#1] ext.html Cycles:180 Average:69616.27 Median:36848.04 stddev:148376.54 (402.7%) stddev-sans-first:148702.10 21:29:36 INFO - PROCESS | 1662 | Values: 524.3 556.6 560.6 584.4 587.7 30161.8 30274.8 37342.0 38690.4 38741.6 42014.0 42304.0 42362.4 42365.4 42560.6 8507.3 9463.0 9696.0 9701.8 9703.5 8637.1 8746.5 8750.6 8778.8 11563.4 10633.5 10907.5 11453.3 11559.3 11574.2 11424.0 11528.1 11530.9 11539.7 11540.1 8602.8 11215.8 11347.4 11666.4 11711.1 38847.4 39134.6 39208.8 39218.6 39247.6 3291.4 3291.8 3297.5 3322.8 3377.1 26707.9 27393.6 27871.7 28247.9 28473.1 2713.8 2749.8 2762.2 2842.7 2850.7 488018.0 490650.0 491953.0 492300.0 493328.5 63238.7 64733.0 64851.8 64882.7 64899.0 67199.0 67462.7 67481.7 67525.7 67739.7 17063.7 17503.6 17598.6 17606.7 17661.9 13626.8 13714.5 13722.5 13735.9 13763.3 14566.3 15516.3 15850.0 17759.4 17812.1 6979.3 7367.0 7493.2 7554.2 7574.5 889.3 983.5 1006.4 1022.8 1067.3 27780.6 27860.6 30094.0 30445.7 30719.0 1077.3 1096.9 1119.2 1175.7 1228.7 15302.8 15649.9 15680.2 15742.6 15871.6 601284.0 609364.0 610301.0 610461.0 611915.0 61589.4 61870.0 61947.4 61990.7 62004.7 1352.8 1480.5 1502.0 1516.2 1538.4 24874.6 29945.7 30243.7 30306.4 30319.8 53814.7 54632.5 54649.7 54718.7 54723.5 552050.0 554957.0 567817.0 569228.0 569728.0 35460.6 41674.6 43577.3 44214.8 44813.6 44473.3 44934.8 45033.3 46191.8 46195.8 36271.9 36897.6 37472.8 37573.6 38358.8 36657.5 36791.8 36798.4 37171.8 37593.8 36620.6 37645.2 37919.1 39083.0 39182.0 33473.0 33558.2 34052.7 34245.5 34325.7 25274.6 25409.1 25596.5 25603.7 25845.0 21:29:36 INFO - PROCESS | 1662 | 21:29:36 INFO - PROCESS | 1662 | [#2] jquery.html Cycles:180 Average:34846.35 Median:11339.86 stddev:102476.99 (903.7%) stddev-sans-first:102731.74 21:29:36 INFO - PROCESS | 1662 | Values: 355.9 399.2 399.8 403.6 407.4 15472.9 16647.0 19775.5 20413.6 20424.5 15702.8 16043.3 16865.0 18178.5 20162.4 5612.1 5681.5 5911.3 5913.0 5920.6 5782.9 5892.3 5893.9 5897.6 5913.0 4141.7 5530.8 6433.3 6437.1 6453.8 6167.3 6402.2 6415.2 6417.1 6417.6 5594.7 5611.4 5615.2 5624.3 5645.0 25685.9 25692.5 25775.0 26045.3 26089.6 2078.8 2303.2 2356.5 2653.2 2820.4 14068.7 14823.4 14890.6 15100.6 15101.9 1433.7 1784.5 1791.1 1815.8 1893.9 103755.4 113412.3 113548.0 113615.0 118695.8 10451.3 10721.6 10972.8 10990.9 11104.7 13808.7 13887.3 13892.3 13906.4 13910.0 7143.6 7606.9 8600.4 8663.3 8763.4 8876.9 8890.8 8900.8 8994.0 8997.6 6444.0 6615.2 6621.6 6639.8 6656.4 1686.9 1834.7 1860.9 1869.2 1879.5 914.1 929.6 979.4 997.1 1066.1 19090.9 19728.8 20600.9 21049.5 21111.9 873.8 1007.7 1014.6 1019.1 1044.6 10145.5 10532.4 10962.5 11205.8 11232.8 597180.0 614921.0 616739.0 628909.0 631777.0 112426.0 114594.4 115693.4 117293.0 119020.0 1027.7 1038.7 1041.2 1042.4 1071.0 17932.7 17935.7 18107.8 18227.8 18878.3 49998.8 50811.7 51473.3 52625.2 53034.4 67505.7 70860.3 71020.6 72616.6 74014.6 7825.1 7864.3 7896.6 7916.9 8183.2 11882.7 12343.3 12363.2 12408.1 12708.1 10641.5 10761.8 11030.5 11084.1 11090.3 9778.0 10269.8 10703.6 10776.5 11053.8 10852.7 10963.2 11343.9 11437.4 11475.0 8123.3 8133.6 8197.0 8264.2 8462.5 9974.6 10990.3 11192.9 11212.6 11415.5 21:29:36 INFO - PROCESS | 1662 | 21:29:36 INFO - PROCESS | 1662 | [#3] mootools.html Cycles:180 Average:5684.73 Median:2784.21 stddev:3201.49 (115.0%) stddev-sans-first:3193.94 21:29:36 INFO - PROCESS | 1662 | Values: 1355.6 1406.1 1568.1 1750.5 1932.1 8002.9 8669.4 8802.2 8808.2 8821.7 8478.4 8715.7 8725.6 8733.7 8740.0 2435.2 2467.0 2469.4 2470.2 2471.2 2390.2 2453.3 2453.7 2455.6 2468.1 2429.9 2458.7 2472.0 2475.2 2484.6 2429.2 2460.3 2465.6 2466.3 2468.8 2409.2 2446.8 2447.4 2459.2 2460.6 8592.4 8837.9 8876.0 8886.6 8956.5 5332.4 5501.8 5537.4 5539.5 5558.4 3534.8 3602.3 3653.8 3674.3 3694.5 1047.7 1062.4 1066.6 1073.3 1101.0 11898.4 11968.6 12110.2 12117.2 12131.7 4529.2 4569.5 4666.6 4688.5 4712.3 9410.4 9421.0 9434.9 9445.8 9445.9 5354.1 5356.7 5374.2 5392.8 5405.1 4434.6 4540.7 4788.4 4947.9 5015.5 3835.9 3849.6 3903.8 3932.7 3959.2 1211.1 1216.2 1218.2 1227.6 1230.1 673.5 786.7 908.9 948.1 959.0 5646.0 5741.9 5828.2 5949.6 5979.1 486.9 504.9 511.2 511.3 665.8 3553.5 3571.1 3849.1 3900.3 3960.3 10584.0 10908.3 10908.8 11031.6 11033.0 10606.3 10742.6 10891.0 10898.3 10988.4 8336.6 8353.6 8379.2 8417.6 8468.4 5495.8 5771.4 5826.4 5854.7 5920.0 9712.3 9726.0 9733.1 9859.3 9896.0 9420.7 9929.5 9943.7 9944.0 9950.0 7013.0 7059.8 7142.2 7147.6 7168.5 8081.5 8336.2 8343.2 8359.2 8371.0 6389.6 6976.8 7323.8 7466.2 7637.0 7202.0 7221.6 7267.4 7350.8 7370.1 6861.5 6887.2 6920.3 6929.6 6944.4 4742.5 4755.8 4849.9 4891.4 4932.4 4930.9 4966.4 4971.2 5057.3 5164.7 21:29:36 INFO - PROCESS | 1662 | 21:29:36 INFO - PROCESS | 1662 | [#4] prototype.html Cycles:180 Average:5525.83 Median:6323.43 stddev:1771.49 (28.0%) stddev-sans-first:1732.83 21:29:36 INFO - PROCESS | 1662 | Values: 319.6 322.3 327.9 330.2 334.1 6327.7 6383.5 6388.4 6436.5 6436.5 6322.1 6324.7 6332.8 6354.7 6368.5 4490.2 4557.1 4629.3 4665.8 4700.3 3895.0 4597.2 4681.0 4683.7 4691.8 4615.0 4615.0 4637.0 4637.9 4640.6 4450.6 4506.9 4611.3 4664.8 4692.5 4038.2 4041.8 4051.5 4055.9 4058.4 6043.7 6084.7 6111.3 6232.2 6233.7 5376.0 5479.3 5484.3 5494.9 5503.1 5663.4 6042.5 6154.8 6683.8 6705.3 4164.7 4383.6 4422.0 4440.3 4506.9 6102.8 7079.0 7125.7 7149.8 7158.5 3762.5 4496.3 4500.6 4504.7 4505.1 4554.0 4554.8 4558.8 4569.3 4583.5 5409.3 5411.7 5425.9 5440.8 5445.3 5786.0 6534.4 6730.1 6808.5 6829.0 1410.9 1559.9 1579.4 1583.1 1584.0 861.9 952.1 957.7 997.4 1016.0 6123.6 6123.8 6130.8 6235.0 6422.7 7114.3 7119.6 7135.2 7135.5 7139.9 6404.4 6411.2 6436.9 6438.1 6444.4 3684.8 3734.2 3736.4 3739.3 3753.1 6171.8 7041.3 7296.3 7337.0 7397.1 7255.7 7265.7 7266.7 7286.6 7326.3 7372.6 7374.4 7379.7 7385.0 7389.9 6795.4 7069.8 7082.7 7087.3 7098.0 6895.9 6926.4 6934.9 6949.0 6966.3 5975.9 7317.5 7349.6 7367.9 7369.1 4479.4 4482.8 4494.1 4500.2 4502.0 5773.7 6964.0 7050.6 7054.1 7055.7 5446.8 6033.3 6804.3 6883.6 6889.3 6823.1 6862.2 6862.2 6869.4 6874.2 6675.8 6688.5 6701.7 6704.6 6730.3 6864.3 6873.1 6898.6 6898.7 6923.2 6436.5 6466.7 6584.2 6654.3 6692.5 21:29:36 INFO - PROCESS | 1662 | 21:29:36 INFO - PROCESS | 1662 | [#5] yui.html Cycles:180 Average:17943.07 Median:634.48 stddev:25109.59 (3957.5%) stddev-sans-first:25146.53 21:29:36 INFO - PROCESS | 1662 | Values: 668.1 691.7 700.2 711.3 756.0 15074.0 15189.2 15364.0 17131.8 17841.8 16087.7 16135.4 16211.4 16227.9 16291.8 728.5 745.0 921.9 943.5 948.0 914.1 922.1 922.6 923.1 923.7 972.9 973.5 975.4 975.8 980.7 763.2 960.9 971.8 974.6 981.8 723.5 724.6 725.4 728.8 735.1 15230.1 15297.8 15316.8 15335.7 15409.2 12241.6 12327.9 12428.8 12545.3 12561.4 10057.0 10110.8 10180.7 10185.1 10289.0 3450.5 3462.2 3488.6 3549.6 3712.8 80422.0 81573.6 82084.8 82156.6 82388.0 9351.1 9424.3 9602.0 9610.3 10146.7 20999.7 21028.9 21045.8 21411.6 21458.9 7355.4 7377.4 7569.5 7591.5 7731.1 4089.8 4123.8 4188.8 4392.0 4411.1 3398.0 3516.2 3529.5 3533.0 3584.0 632.9 651.0 655.3 660.9 661.2 519.4 607.3 614.9 626.0 636.1 7671.6 7833.6 7878.8 8469.3 8809.0 540.8 586.8 613.0 620.1 626.7 3700.0 3812.4 3869.2 3898.8 3931.9 88033.1 89562.1 90716.5 91020.0 91099.1 58383.7 72333.6 74069.6 74166.0 76179.0 70070.0 70283.0 70426.6 70475.0 71427.0 6701.6 7644.8 7790.9 7917.8 8024.0 47959.8 47972.0 48779.8 49195.0 49420.5 64692.0 65056.7 66856.7 66862.3 68331.7 9973.9 10001.9 10094.1 10181.2 10624.0 10862.7 10883.1 11135.2 11325.5 11982.2 10182.3 10486.6 10651.4 10708.6 10732.7 10478.3 10601.9 10742.6 10778.4 10982.7 10213.7 10260.9 10263.0 10412.3 11033.8 8688.3 8962.6 9143.6 9209.4 9296.2 8899.4 8916.8 8957.9 9008.3 9080.1 21:29:36 INFO - PROCESS | 1662 | -------- Summary: end -------- 21:29:36 INFO - PROCESS | 1662 | 21:29:36 INFO - TEST-INFO | 1662: exit 0 21:29:36 INFO - TEST-OK | dromaeo_css | took 1115675ms 21:29:36 INFO - TEST-START | kraken 21:29:36 INFO - operating with platform_type : mac_ 21:29:36 INFO - Initialising browser for kraken test... 21:29:37 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/tmpRm0Hn1/profile http://localhost:49273/getInfo.html) 21:29:39 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 21:29:39 INFO - PROCESS | 1671 | colorDepth:24 21:29:39 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 21:29:39 INFO - PROCESS | 1671 | __metrics 21:29:39 INFO - PROCESS | 1671 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 21:29:39 ERROR - PROCESS | 1671 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 21:29:40 INFO - TEST-INFO | 1671: exit 0 21:29:40 INFO - Browser initialized. 21:29:40 INFO - Running cycle 1/1 for kraken test... 21:29:40 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/tmpRm0Hn1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:29:52 INFO - PROCESS | 1673 | RSS: Main: 210644992 21:29:52 INFO - PROCESS | 1673 | 21:29:54 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection) 21:29:55 INFO - PROCESS | 1673 | RSS: Main: 272101376 21:29:55 INFO - PROCESS | 1673 | 21:29:58 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-dft) 21:29:58 INFO - PROCESS | 1673 | RSS: Main: 286015488 21:29:58 INFO - PROCESS | 1673 | 21:30:02 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-fft) 21:30:02 INFO - PROCESS | 1673 | RSS: Main: 372531200 21:30:02 INFO - PROCESS | 1673 | 21:30:04 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator) 21:30:05 INFO - PROCESS | 1673 | RSS: Main: 278065152 21:30:05 INFO - PROCESS | 1673 | 21:30:07 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:30:08 INFO - PROCESS | 1673 | RSS: Main: 279994368 21:30:08 INFO - PROCESS | 1673 | 21:30:11 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom) 21:30:11 INFO - PROCESS | 1673 | RSS: Main: 317120512 21:30:11 INFO - PROCESS | 1673 | 21:30:14 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate) 21:30:15 INFO - PROCESS | 1673 | RSS: Main: 318177280 21:30:15 INFO - PROCESS | 1673 | 21:30:18 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial) 21:30:18 INFO - PROCESS | 1673 | RSS: Main: 317554688 21:30:18 INFO - PROCESS | 1673 | 21:30:20 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:30:21 INFO - PROCESS | 1673 | RSS: Main: 309243904 21:30:21 INFO - PROCESS | 1673 | 21:30:22 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:30:23 INFO - PROCESS | 1673 | RSS: Main: 315715584 21:30:23 INFO - PROCESS | 1673 | 21:30:25 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:30:26 INFO - PROCESS | 1673 | RSS: Main: 318717952 21:30:26 INFO - PROCESS | 1673 | 21:30:29 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:30:29 INFO - PROCESS | 1673 | RSS: Main: 323768320 21:30:29 INFO - PROCESS | 1673 | 21:30:32 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:30:33 INFO - PROCESS | 1673 | RSS: Main: 321703936 21:30:33 INFO - PROCESS | 1673 | 21:30:35 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49273/tests/kraken/driver.html?testName=ai-astar) 21:30:35 INFO - PROCESS | 1673 | RSS: Main: 385630208 21:30:35 INFO - PROCESS | 1673 | 21:30:35 INFO - PROCESS | 1673 | __start_tp_report 21:30:35 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 21:30:35 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 21:30:35 INFO - PROCESS | 1673 | |i|pagename|runs| 21:30:35 INFO - PROCESS | 1673 | |0;ai-astar;113;113;120;113;112;113;112;114;114;133 21:30:35 INFO - PROCESS | 1673 | |1;audio-beat-detection;155;122;155;152;120;138;119;151;159;117 21:30:35 INFO - PROCESS | 1673 | |2;audio-dft;190;185;186;185;186;193;253;208;184;185 21:30:35 INFO - PROCESS | 1673 | |3;audio-fft;76;74;79;151;63;75;77;86;156;71 21:30:35 INFO - PROCESS | 1673 | |4;audio-oscillator;90;90;144;76;81;87;122;106;107;159 21:30:35 INFO - PROCESS | 1673 | |5;imaging-gaussian-blur;87;86;89;86;86;85;88;85;89;89 21:30:35 INFO - PROCESS | 1673 | |6;imaging-darkroom;92;93;94;101;132;87;138;94;93;110 21:30:35 INFO - PROCESS | 1673 | |7;imaging-desaturate;86;87;86;85;86;87;102;86;87;85 21:30:35 INFO - PROCESS | 1673 | |8;json-parse-financial;58;64;63;64;64;66;64;78;61;58 21:30:35 INFO - PROCESS | 1673 | |9;json-stringify-tinderbox;63;66;63;78;79;76;69;67;68;71 21:30:35 INFO - PROCESS | 1673 | |10;stanford-crypto-aes;81;78;80;82;80;81;80;79;83;85 21:30:35 INFO - PROCESS | 1673 | |11;stanford-crypto-ccm;116;118;143;138;140;120;129;128;149;131 21:30:35 INFO - PROCESS | 1673 | |12;stanford-crypto-pbkdf2;167;164;167;178;214;167;171;166;163;168 21:30:35 INFO - PROCESS | 1673 | |13;stanford-crypto-sha256-iterative;68;67;78;93;94;97;92;65;67;68 21:30:35 INFO - PROCESS | 1673 | __end_tp_report 21:30:35 INFO - PROCESS | 1673 | __start_cc_report 21:30:35 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,2085 21:30:35 INFO - PROCESS | 1673 | __end_cc_report 21:30:35 INFO - PROCESS | 1673 | __startTimestamp1463718635302__endTimestamp 21:30:35 INFO - PROCESS | 1673 | 21:30:35 INFO - PROCESS | 1673 | ------- Summary: start ------- 21:30:35 INFO - PROCESS | 1673 | Number of tests: 14 21:30:35 INFO - PROCESS | 1673 | 21:30:35 INFO - PROCESS | 1673 | [#0] ai-astar Cycles:10 Average:115.70 Median:113.50 stddev:6.50 (5.7%) stddev-sans-first:6.82 21:30:35 INFO - PROCESS | 1673 | Values: 113.0 113.0 120.0 113.0 112.0 113.0 112.0 114.0 114.0 133.0 21:30:35 INFO - PROCESS | 1673 | 21:30:35 INFO - PROCESS | 1673 | [#1] audio-beat-detection Cycles:10 Average:138.80 Median:151.50 stddev:17.51 (11.6%) stddev-sans-first:17.56 21:30:35 INFO - PROCESS | 1673 | Values: 155.0 122.0 155.0 152.0 120.0 138.0 119.0 151.0 159.0 117.0 21:30:35 INFO - PROCESS | 1673 | 21:30:35 INFO - PROCESS | 1673 | [#2] audio-dft Cycles:10 Average:195.50 Median:188.00 stddev:21.45 (11.4%) stddev-sans-first:22.66 21:30:35 INFO - PROCESS | 1673 | Values: 190.0 185.0 186.0 185.0 186.0 193.0 253.0 208.0 184.0 185.0 21:30:35 INFO - PROCESS | 1673 | 21:30:35 INFO - PROCESS | 1673 | [#3] audio-fft Cycles:10 Average:90.80 Median:75.50 stddev:33.57 (44.5%) stddev-sans-first:35.18 21:30:35 INFO - PROCESS | 1673 | Values: 76.0 74.0 79.0 151.0 63.0 75.0 77.0 86.0 156.0 71.0 21:30:35 INFO - PROCESS | 1673 | 21:30:35 INFO - PROCESS | 1673 | [#4] audio-oscillator Cycles:10 Average:106.20 Median:78.50 stddev:27.70 (35.3%) stddev-sans-first:28.76 21:30:35 INFO - PROCESS | 1673 | Values: 90.0 90.0 144.0 76.0 81.0 87.0 122.0 106.0 107.0 159.0 21:30:35 INFO - PROCESS | 1673 | 21:30:35 INFO - PROCESS | 1673 | [#5] imaging-gaussian-blur Cycles:10 Average:87.00 Median:87.50 stddev:1.63 (1.9%) stddev-sans-first:1.73 21:30:35 INFO - PROCESS | 1673 | Values: 87.0 86.0 89.0 86.0 86.0 85.0 88.0 85.0 89.0 89.0 21:30:35 INFO - PROCESS | 1673 | 21:30:35 INFO - PROCESS | 1673 | [#6] imaging-darkroom Cycles:10 Average:103.40 Median:92.50 stddev:17.82 (19.3%) stddev-sans-first:18.41 21:30:35 INFO - PROCESS | 1673 | Values: 92.0 93.0 94.0 101.0 132.0 87.0 138.0 94.0 93.0 110.0 21:30:35 INFO - PROCESS | 1673 | 21:30:35 INFO - PROCESS | 1673 | [#7] imaging-desaturate Cycles:10 Average:87.70 Median:86.00 stddev:5.08 (5.9%) stddev-sans-first:5.35 21:30:35 INFO - PROCESS | 1673 | Values: 86.0 87.0 86.0 85.0 86.0 87.0 102.0 86.0 87.0 85.0 21:30:35 INFO - PROCESS | 1673 | 21:30:35 INFO - PROCESS | 1673 | [#8] json-parse-financial Cycles:10 Average:64.00 Median:64.00 stddev:5.60 (8.7%) stddev-sans-first:5.50 21:30:35 INFO - PROCESS | 1673 | Values: 58.0 64.0 63.0 64.0 64.0 66.0 64.0 78.0 61.0 58.0 21:30:35 INFO - PROCESS | 1673 | 21:30:35 INFO - PROCESS | 1673 | [#9] json-stringify-tinderbox Cycles:10 Average:70.00 Median:70.00 stddev:5.87 (8.4%) stddev-sans-first:5.65 21:30:35 INFO - PROCESS | 1673 | Values: 63.0 66.0 63.0 78.0 79.0 76.0 69.0 67.0 68.0 71.0 21:30:35 INFO - PROCESS | 1673 | 21:30:35 INFO - PROCESS | 1673 | [#10] stanford-crypto-aes Cycles:10 Average:80.90 Median:81.00 stddev:2.02 (2.5%) stddev-sans-first:2.15 21:30:35 INFO - PROCESS | 1673 | Values: 81.0 78.0 80.0 82.0 80.0 81.0 80.0 79.0 83.0 85.0 21:30:35 INFO - PROCESS | 1673 | 21:30:35 INFO - PROCESS | 1673 | [#11] stanford-crypto-ccm Cycles:10 Average:131.20 Median:134.50 stddev:11.18 (8.3%) stddev-sans-first:10.42 21:30:35 INFO - PROCESS | 1673 | Values: 116.0 118.0 143.0 138.0 140.0 120.0 129.0 128.0 149.0 131.0 21:30:35 INFO - PROCESS | 1673 | 21:30:35 INFO - PROCESS | 1673 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.50 Median:167.50 stddev:15.17 (9.1%) stddev-sans-first:15.96 21:30:35 INFO - PROCESS | 1673 | Values: 167.0 164.0 167.0 178.0 214.0 167.0 171.0 166.0 163.0 168.0 21:30:35 INFO - PROCESS | 1673 | 21:30:35 INFO - PROCESS | 1673 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.90 Median:85.00 stddev:13.50 (15.9%) stddev-sans-first:13.73 21:30:35 INFO - PROCESS | 1673 | Values: 68.0 67.0 78.0 93.0 94.0 97.0 92.0 65.0 67.0 68.0 21:30:35 INFO - PROCESS | 1673 | -------- Summary: end -------- 21:30:35 INFO - PROCESS | 1673 | 21:30:36 INFO - TEST-INFO | 1673: exit 0 21:30:36 INFO - TEST-OK | kraken | took 59067ms 21:30:36 INFO - SUITE-END | took 1174s 21:30:36 INFO - Completed test suite (00:19:35) 21:30:36 INFO - javascript benchmark 21:30:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3333.1167240681225, 3391.304782519582, 3407.693307602316, 3446.517487551742, 3470.132466883279, 5615.578831587637, 5632.549396048316, 5635.830925072244, 5659.066254068079, 5663.858403539908, 5622.240997465341, 5675.971620141904, 5680.772769089232, 5691.743871525781, 5699.344575373833, 5039.470855560168, 5080.644354895159, 5130.281760553526, 5162.845114646567, 5204.0, 5196.844094677166, 5197.896042079156, 5198.7660555274915, 5209.687418754868, 5212.061828870802, 5102.1836506159025, 5167.147420675584, 5170.793168273264, 5180.300659410969, 5202.479752024805, 5183.352080989857, 5204.453532379083, 5213.843584692465, 5214.921776173361, 5261.631685781997, 5017.197248440232, 5055.34280543527, 5071.087204303224, 5151.871203219912, 5154.793808247703, 5530.336359636869, 5559.8888022239735, 5576.470235327637, 5584.1902924076185, 5627.5779316551425, 6143.047827586686, 6173.32093469718, 6182.96908515459, 6185.041318595625, 6186.690665466709, 6090.23872015998, 6150.954337762591, 6185.597936134137, 6260.186175797163, 6275.843103922439, 5684.8863022739315, 5735.455131762559, 5737.082066869364, 5746.166805813175, 5776.249087618625, 6224.564280500322, 6228.968855155696, 6243.406876346739, 6247.125402443661, 6263.997760358412, 5924.377940316291, 6031.8793624127275, 6041.78853740117, 6095.116208149793, 6096.207493025967, 6197.194364732556, 6215.502759779207, 6216.906746398808, 6231.127642130105, 6254.124422580842, 6076.301225359052, 6122.877542449126, 6135.9079613805825, 6139.2325959255095, 6172.074188871705, 6050.6974651267265, 6115.240341084353, 6116.1836049259855, 6190.350013248634, 6202.782902598387, 4805.591524720368, 4842.878928026757, 4846.975765121222, 4851.199552073939, 4857.514248575114, 4369.126174764996, 4401.317795741666, 4405.163019026375, 4442.444694413199, 4486.237339652267, 5620.662760234317, 5634.52106570946, 5636.154576813429, 5655.1517272409465, 5655.293088363955, 6020.57855950088, 6181.598196117238, 6244.219472565929, 6247.5001999840815, 6266.310705822356, 5595.300587426572, 5614.073677843192, 5617.297837770278, 5617.325920889438, 5651.519620832193, 5289.2859463972645, 5309.150535914235, 5314.867128321823, 5317.361916569959, 5323.920141197885, 6199.938000620026, 6219.626822390671, 6267.059941008885, 6270.404311590483, 6272.153259310026, 6262.060690896402, 6269.811905642838, 6270.49836013127, 6273.9372606273355, 6280.183576135073, 6253.624782512882, 6260.655663938527, 6261.655608941552, 6266.0, 6266.404691554295, 4516.593506584291, 5934.703264836655, 5948.048312270016, 5951.672658003678, 5980.671063091398, 6023.156758053964, 6146.2175968968495, 6147.538934580014, 6176.567640265138, 6181.443670069715, 6028.186194863637, 6035.36628653985, 6058.636481811105, 6071.514278857592, 6074.42292982166, 5994.400559943971, 6041.78853740117, 6059.787907423219, 6061.3029501606115, 6069.02895536712, 5717.714114294352, 5782.2483077199695, 5824.504917082096, 5830.5044071254415, 5846.1815345852465, 5945.702714864326, 5965.373635768182, 5986.4013598639785, 5989.131575921554, 6078.817635470943, 5841.2406387171095, 5875.059990401515, 5911.408859114054, 5919.171316015675, 5943.64338139713], "value": 5638.36930949991, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [524.326240780597, 556.568659289051, 560.6103757888269, 584.400988986289, 587.6630665236589, 30161.849190754045, 30274.84862575687, 37342.0, 38690.419643705296, 38741.61258387415, 42014.0, 42304.0, 42362.364564531556, 42365.36451953223, 42560.57439425605, 8507.276881465083, 9462.952685236582, 9696.030396960317, 9701.75745606362, 9703.466309352983, 8637.136286371344, 8746.518941458216, 8750.562471876412, 8778.824423511525, 11563.364014979215, 10633.521491532862, 10907.454627268644, 11453.255538389978, 11559.306441613488, 11574.24767390121, 11424.0, 11528.077753779677, 11530.942345288304, 11539.653810385702, 11540.076793856471, 8602.827943441158, 11215.831762523569, 11347.432628368548, 11666.416679166008, 11711.121665875009, 38847.41728874069, 39134.60865391338, 39208.80395598032, 39218.60781392178, 39247.60752392468, 3291.358185153896, 3291.753118516121, 3297.5383446317533, 3322.767406281537, 3377.1219482934366, 26707.866460667767, 27393.589096163574, 27871.721282787115, 28247.858760706065, 28473.145805625863, 2713.81003329767, 2749.752522273005, 2762.1575419497044, 2842.6873043965147, 2850.686424493304, 488018.0, 490650.0, 491953.0, 492300.0, 493328.5333573309, 63238.68380658159, 64733.02900456497, 64851.757412129205, 64882.70234595281, 64899.0, 67199.0, 67462.66268668625, 67481.66259168673, 67525.66237168782, 67739.66130169416, 17063.744043839353, 17503.64992700139, 17598.648027039388, 17606.735898961524, 17661.911690441724, 13626.795598066037, 13714.51999180024, 13722.45110195601, 13735.931320343334, 13763.31183440844, 14566.344514496874, 15516.29320774714, 15850.0, 17759.378421755177, 17812.10939453022, 6979.30206979298, 7367.042284503087, 7493.175750667531, 7554.169041405441, 7574.545527268271, 889.2885691446817, 983.4836710726926, 1006.3609607899036, 1022.8465730140539, 1067.3062509368972, 27780.58329125065, 27860.582091268647, 30094.0, 30445.69554304473, 30719.0, 1077.2782235901964, 1096.9341839489657, 1119.2333251722596, 1175.7060734816296, 1228.7112528555783, 15302.770458443132, 15649.921750391402, 15680.215989200495, 15742.646767744709, 15871.603209919613, 601284.0, 609364.0, 610301.0, 610461.0, 611915.0, 61589.384106158366, 61870.0, 61947.38052619506, 61990.69004654948, 62004.68997654983, 1352.8038434426953, 1480.4670318685264, 1501.96996060076, 1516.2343016776458, 1538.3769573322822, 24874.626880597167, 29945.70054299429, 30243.72727680709, 30306.393872122873, 30319.848400757855, 53814.73092634512, 54632.45367546273, 54649.72675136599, 54718.726406367714, 54723.45276547343, 552050.0, 554957.0, 567817.0, 569228.0, 569728.0, 35460.64539354573, 41674.58325416828, 43577.346339804295, 44214.778926105166, 44813.551864480934, 44473.33290000588, 44934.77532612316, 45033.32450013318, 46191.76904115458, 46195.76902115468, 36271.91184264476, 36897.63102368942, 37472.81263593664, 37573.62426375701, 38358.80820595991, 36657.45013824741, 36791.81604091962, 36798.44802327914, 37171.81414092912, 37593.81203093967, 36620.63379366279, 37645.24709505849, 37919.05202369963, 39083.0, 39182.0, 33473.0, 33558.16104597307, 34052.65947340594, 34245.486317705756, 34325.65674343224, 25274.620880687173, 25409.110681126072, 25596.48807023812, 25603.743962560882, 25845.0], "value": 20415.198486917547, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.9056849934767, 399.24917319667514, 399.8140864498006, 403.56213508343444, 407.42553000269623, 15472.922635386836, 16647.0, 19775.5056123597, 20413.591728165447, 20424.489387765312, 15702.76445853313, 16043.278052487636, 16864.988100713934, 18178.4546463606, 20162.395128146178, 5612.07400778871, 5681.545476361881, 5911.261092363455, 5913.0, 5920.5559583031445, 5782.9421705782825, 5892.322382925954, 5893.941060589404, 5897.616654917438, 5913.0, 4141.7100802943805, 5530.806421775239, 6433.292337842833, 6437.130987316699, 6453.774117905874, 6167.290761562433, 6402.167718196652, 6415.230172379321, 6417.0695249189075, 6417.647029413403, 5594.692291923942, 5611.354694210137, 5615.241942337773, 5624.29696287964, 5645.0, 25685.871570642026, 25692.48615027689, 25775.0, 26045.265247149156, 26089.608655870175, 2078.8233000195, 2303.2284184798114, 2356.4580146566386, 2653.1775149703653, 2820.365417780983, 14068.71862562743, 14823.407063717439, 14890.627734306729, 15100.622484437867, 15101.924490377478, 1433.655922578584, 1784.4914199453094, 1791.0507431061558, 1815.818418158187, 1893.8958357290344, 103755.44366834503, 113412.29881551783, 113548.0, 113615.0, 118695.81304186933, 10451.320664156805, 10721.571137154582, 10972.780544389068, 10990.945045274722, 11104.666859994213, 13808.654783630289, 13887.305634718427, 13892.305384730724, 13906.4437422504, 13910.0, 7143.607101609461, 7606.885896711554, 8600.354973376896, 8663.306935445149, 8763.430377025474, 8876.8668469973, 8890.822183556422, 8900.777480562909, 8994.010658827523, 8997.550122493849, 6444.033394990695, 6615.17310336208, 6621.602703837785, 6639.834004149935, 6656.434203092695, 1686.8566171875282, 1834.7339635752742, 1860.8555738221166, 1869.1962456143988, 1879.5207222158263, 914.1133100892257, 929.5538141691891, 979.4221409368507, 997.0826839307854, 1066.0842595501297, 19090.904545477184, 19728.816271023497, 20600.896995514926, 21049.473763156042, 21111.8944405277, 873.8421591391304, 1007.7379881230933, 1014.5789497358513, 1019.0522813783252, 1044.5717255925035, 10145.543450544743, 10532.36805791655, 10962.451877406258, 11205.831912521318, 11232.831507527393, 597180.0, 614921.0, 616739.0, 628909.0, 631777.0, 112426.0, 114594.42702786432, 115693.42153289179, 117293.0, 119020.0, 1027.732789474727, 1038.7299302181486, 1041.2034793383068, 1042.3954106618144, 1071.0307172009343, 17932.731009035135, 17935.73096403529, 18107.81892181114, 18227.817721823143, 18878.3392581259, 49998.75003124951, 50811.72970675763, 51473.29098246017, 52625.15811946563, 53034.40896773103, 67505.66247168733, 70860.29139708537, 71020.57958840897, 72616.6369168172, 74014.62992685002, 7825.100113487022, 7864.2922137007945, 7896.605169741375, 7916.920830791619, 8183.222593853575, 11882.702932426413, 12343.259404435763, 12363.196392234477, 12408.069394795246, 12708.110432269652, 10641.467926603484, 10761.784764304826, 11030.50362733695, 11084.113270938467, 11090.334579925231, 9777.973312802053, 10269.84595231087, 10703.571857125628, 10776.515056822305, 11053.83419248728, 10852.728681783019, 10963.177761668067, 11343.943280283545, 11437.370944598128, 11475.024622907147, 8123.252077672242, 8133.593320333841, 8197.016358036835, 8264.173582641928, 8462.534560599226, 9974.551145198338, 10990.340579565252, 11192.944035280098, 11212.55149793999, 11415.486303116546], "value": 9638.205051776751, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1355.6272025193073, 1406.0790182430499, 1568.059164501299, 1750.5273576134443, 1932.091916799104, 8002.8799568006525, 8669.436486628365, 8802.163794439533, 8808.163224493663, 8821.735347939564, 8478.406511544194, 8715.651373945035, 8725.5637218139, 8733.65065397385, 8739.956300218506, 2435.1598698449006, 2466.987665061672, 2469.444375015625, 2470.22188010776, 2471.1968610201666, 2390.2231774673214, 2453.251758213744, 2453.717822450414, 2455.6193789962495, 2468.0744720729726, 2429.866357350345, 2458.6680798092298, 2472.0, 2475.207933461293, 2484.6024636058232, 2429.161939131006, 2460.2988148377626, 2465.6424818401233, 2466.3094333586623, 2468.827182097254, 2409.168836751318, 2446.755324467557, 2447.3514518652573, 2459.1884678056376, 2460.5570997220493, 8592.355573332025, 8837.911620883773, 8876.0, 8886.644534218623, 8956.507392093432, 5332.440093790135, 5501.834944951658, 5537.390887002426, 5539.473749993744, 5558.416366281523, 3534.805585692786, 3602.2975519773654, 3653.799041052741, 3674.3018826422895, 3694.519712437366, 1047.701405099543, 1062.4262898035051, 1066.648006157966, 1073.2540884085563, 1100.9614663486739, 11898.405079746064, 11968.581099661556, 12110.212836165621, 12117.151799374047, 12131.696707582292, 4529.230030894756, 4569.520200378979, 4666.626669866402, 4688.531146885284, 4712.340272361872, 9410.388324759006, 9421.0, 9434.90565094354, 9445.76385590352, 9445.905540944503, 5354.089804733127, 5356.65181763192, 5374.193870919394, 5392.838214853561, 5405.13517837152, 4434.600885920218, 4540.659450541157, 4788.377510923558, 4947.925781113287, 5015.523525265094, 3835.9041023974623, 3849.576546579874, 3903.785291808921, 3932.744371615893, 3959.1883663848844, 1211.1400905357118, 1216.1730023584048, 1218.2264262193394, 1227.6194379742308, 1230.0565107130712, 673.5251647588439, 786.7049856303886, 908.8863892013499, 948.0519480519481, 959.0217977662884, 5646.0, 5741.913871291934, 5828.154917536934, 5949.613275137102, 5979.103134529768, 486.88071422501713, 504.8990201959623, 511.15659162382366, 511.3480312601463, 665.8048802399704, 3553.5202747629255, 3571.0715214044535, 3849.1339448623835, 3900.258950799339, 3960.346542820402, 10584.0, 10908.290961087503, 10908.836367454494, 11031.558737650565, 11033.0, 10606.257561970588, 10742.570297188026, 10891.0, 10898.291611045257, 10988.395638239974, 8336.583170841555, 8353.582320884054, 8379.162083791694, 8417.621207045697, 8468.364872634576, 5495.752691128829, 5771.422857714195, 5826.446487583673, 5854.736536855937, 5920.023196172756, 9712.271579631415, 9726.027397260217, 9733.124018838338, 9859.30984831055, 9896.0, 9420.670276540288, 9929.503524823585, 9943.651972180938, 9944.005599439997, 9950.0, 7012.964935175291, 7059.82350441243, 7142.214356420685, 7147.606881621561, 7168.498205125591, 8081.4747041442115, 8336.249737523653, 8343.207395297437, 8359.205875441823, 8371.0, 6389.552731308764, 6976.7558135465015, 7323.780286591411, 7466.216047314953, 7637.0, 7202.027726256888, 7221.602811845399, 7267.382272506896, 7350.77947661571, 7370.078740157481, 6861.519693621399, 6887.173539175331, 6920.273371295942, 6929.618870961944, 6944.375006249462, 4742.502037286174, 4755.762211889322, 4849.927251091166, 4891.413030436371, 4932.383452068491, 4930.852074437773, 4966.428860680923, 4971.229459433794, 5057.266696329085, 5164.715940623302], "value": 4514.577065697327, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [319.56060416926726, 322.25558958805163, 327.8737685990894, 330.24044697196456, 334.0829423233225, 6327.715252813623, 6383.457406120479, 6388.425041746248, 6436.452901503366, 6436.485081193505, 6322.146510221119, 6324.715387807548, 6332.841678958017, 6354.68226588671, 6368.458681012119, 4490.214212512814, 4557.08858228354, 4629.328747331635, 4665.813367465297, 4700.318453824193, 3895.0, 4597.218472859622, 4681.016986432854, 4683.742394168319, 4691.812327506896, 4614.953850461503, 4615.0, 4636.953630463703, 4637.8840528986875, 4640.582347588716, 4450.57719516647, 4506.909861802778, 4611.2852507861335, 4664.836730714409, 4692.460367057798, 4038.172174704179, 4041.7777022263763, 4051.493563304587, 4055.9112057290517, 4058.431819545266, 6043.7280322385595, 6084.695765211722, 6111.297200821873, 6232.1898153240245, 6233.688315584202, 5375.978564072813, 5479.2602998595075, 5484.2596249506605, 5494.862628434281, 5503.064479038573, 5663.433656634304, 6042.546808989343, 6154.799814036265, 6683.832904177434, 6705.2624211336715, 4164.68764842638, 4383.649308055348, 4421.955780442187, 4440.312681181163, 4506.8873278167985, 6102.816915492543, 7078.964605176992, 7125.679344429462, 7149.821254468628, 7158.534695244793, 3762.492063571383, 4496.258117410656, 4500.594946454769, 4504.684672072989, 4505.098980203972, 4554.0, 4554.795034223468, 4558.817647294071, 4569.2689169732685, 4583.495815460296, 5409.26974858397, 5411.702356370359, 5425.945740542623, 5440.755166017539, 5445.264889239981, 5786.0, 6534.379233972765, 6730.091437655951, 6808.489363297773, 6829.0, 1410.8941829362839, 1559.8752099831986, 1579.3840402243134, 1583.1371902313037, 1583.9841601584067, 861.9439736417113, 952.0958083832336, 957.7262241219162, 997.3666721631671, 1016.009390843936, 6123.601965872204, 6123.755049798047, 6130.754769809158, 6234.973633569108, 6422.74309027643, 7114.253003434668, 7119.5728256303755, 7135.215126336099, 7135.500514963903, 7139.857202855914, 6404.391582799627, 6411.198600174978, 6436.935630643634, 6438.098666186737, 6444.355564443518, 3684.8157592120288, 3734.1971476132762, 3736.4021756518828, 3739.270842185748, 3753.099256178552, 6171.783987560414, 7041.260667629927, 7296.306850849161, 7336.96331518339, 7397.149327827261, 7255.746048888263, 7265.745698900513, 7266.709331626781, 7286.562806231643, 7326.3406293433845, 7372.557646541225, 7374.446916481285, 7379.6679149438405, 7385.0, 7389.926100738924, 6795.38841504267, 7069.752558660422, 7082.716691332495, 7087.255838136922, 7097.964510177416, 6895.896561551681, 6926.411255043377, 6934.930650693428, 6949.0, 6966.268541803038, 5975.910361344497, 7317.487775855639, 7349.55902645843, 7367.926320736724, 7369.078865141857, 4479.417675702138, 4482.775861206992, 4494.101179764126, 4500.212462819059, 4502.009557897389, 5773.74018169175, 6963.990221417967, 7050.612216328152, 7054.082969214174, 7055.682494287872, 5446.836594902159, 6033.306169790355, 6804.319568043156, 6883.621400823003, 6889.345512176335, 6823.112995310578, 6862.176538815374, 6862.210845752603, 6869.416099631588, 6874.175098988154, 6675.7997260082275, 6688.464922806066, 6701.698423571049, 6704.597724136567, 6730.293319201609, 6864.313568643096, 6873.1064961554675, 6898.620575868375, 6898.689558969758, 6923.2038315594355, 6436.485081193399, 6466.741330346734, 6584.176977877765, 6654.3012983637955, 6692.498062645223], "value": 4931.483198477718, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [668.0547025958269, 691.7475121580624, 700.1912790726713, 711.3029231353275, 755.9584222867744, 15074.0, 15189.164595947232, 15363.999999999985, 17131.828681713178, 17841.821581784177, 16087.678246435093, 16135.43525976591, 16211.432599859007, 16227.94518356306, 16291.755623665626, 728.5155371677854, 744.9962750186241, 921.9078092190761, 943.5329511891623, 947.9763005924838, 914.0813482450128, 922.0502882031518, 922.6125027488438, 923.1368670293264, 923.7367350305165, 972.9464879431629, 973.4548652754445, 975.4293738163183, 975.790205105902, 980.6616717232544, 763.1567118334216, 960.851068084448, 971.8153550825356, 974.5565767575736, 981.7594689301131, 723.5260904107818, 724.6231959381092, 725.4269127389356, 728.83236855523, 735.1141874041767, 15230.086194828345, 15297.847021529753, 15316.846831531653, 15335.693286134216, 15409.152496612682, 12241.571544995972, 12327.938360308231, 12428.75142497145, 12545.31000794956, 12561.371931403393, 10057.0, 10110.797784044351, 10180.694579162637, 10185.08334249921, 10288.971102889725, 3450.4824276358745, 3462.169079420946, 3488.5988111367265, 3549.5917969433585, 3712.7586706864236, 80422.0, 81573.59213203956, 82084.76872846912, 82156.58921705413, 82388.0, 9351.111644393772, 9424.293178011676, 9602.039796020481, 9610.327277090677, 10146.695599132037, 20999.685004724943, 21028.894855525625, 21045.790192029617, 21411.571768564543, 21458.892705536684, 7355.448341374418, 7377.372923301472, 7569.5079819811535, 7591.506552074097, 7731.149573546905, 4089.795510224477, 4123.752574845519, 4188.769617670996, 4391.956080439219, 4411.117776444724, 3398.0, 3516.24400753834, 3529.505869178317, 3532.9646703532635, 3584.0, 632.8576070384127, 650.9707063182168, 655.2857385449882, 660.8645227728305, 661.2197606823897, 519.4104691175543, 607.3167686352853, 614.9108379284978, 625.9765431854585, 636.096742625473, 7671.6164191790185, 7833.64748586315, 7878.763637090897, 8469.280111190494, 8809.0, 540.7701726766109, 586.7946218823378, 613.0191693290699, 620.1028221612497, 626.674129452682, 3699.963000370016, 3812.390017597171, 3869.2454971280895, 3898.8050597470015, 3931.8623848165175, 88033.11966880377, 89562.10437895668, 90716.5464172675, 91020.0, 91099.08900911038, 58383.70808145932, 72333.638331808, 74069.6296518514, 74166.0, 76179.0, 70070.0, 70283.0, 70426.64786676034, 70475.0, 71427.0, 6701.597904125768, 7644.770656880302, 7790.948221989959, 7917.802054948673, 8024.037115546172, 47959.760201200166, 47972.04055918932, 48779.75610121927, 49195.0, 49420.50579494303, 64692.0, 65056.72301469426, 66856.66571667306, 66862.33137668562, 68331.65834170996, 9973.900260997298, 10001.899981000097, 10094.091531762177, 10181.185505159421, 10624.043836054792, 10862.674119776419, 10883.129349652167, 11135.220534562503, 11325.490352933974, 11982.161248712506, 10182.338148020355, 10486.632967846088, 10651.360918344924, 10708.571657133627, 10732.73168170802, 10478.266521343738, 10601.946990265, 10742.62400815999, 10778.407187604756, 10982.725431864268, 10213.723284589427, 10260.948695256477, 10262.973702629677, 10412.27114102036, 11033.83449248278, 8688.348373872112, 8962.596683149144, 9143.588538515649, 9209.447433154031, 9296.163345298955, 8899.421537600036, 8916.821663566563, 8957.910420895707, 9008.324375671982, 9080.137386948229], "value": 6831.4253674663705, "unit": "score"}], "name": "dromaeo_css", "value": 7433.756239941132, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 120.0, 113.0, 112.0, 113.0, 112.0, 114.0, 114.0, 133.0], "value": 115.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 122.0, 155.0, 152.0, 120.0, 138.0, 119.0, 151.0, 159.0, 117.0], "value": 138.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [190.0, 185.0, 186.0, 185.0, 186.0, 193.0, 253.0, 208.0, 184.0, 185.0], "value": 195.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 74.0, 79.0, 151.0, 63.0, 75.0, 77.0, 86.0, 156.0, 71.0], "value": 90.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 144.0, 76.0, 81.0, 87.0, 122.0, 106.0, 107.0, 159.0], "value": 106.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 89.0, 86.0, 86.0, 85.0, 88.0, 85.0, 89.0, 89.0], "value": 87.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 94.0, 101.0, 132.0, 87.0, 138.0, 94.0, 93.0, 110.0], "value": 103.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 86.0, 85.0, 86.0, 87.0, 102.0, 86.0, 87.0, 85.0], "value": 87.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 64.0, 63.0, 64.0, 64.0, 66.0, 64.0, 78.0, 61.0, 58.0], "value": 64.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 66.0, 63.0, 78.0, 79.0, 76.0, 69.0, 67.0, 68.0, 71.0], "value": 70.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 78.0, 80.0, 82.0, 80.0, 81.0, 80.0, 79.0, 83.0, 85.0], "value": 80.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 118.0, 143.0, 138.0, 140.0, 120.0, 129.0, 128.0, 149.0, 131.0], "value": 131.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 164.0, 167.0, 178.0, 214.0, 167.0, 171.0, 166.0, 163.0, 168.0], "value": 172.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 78.0, 93.0, 94.0, 97.0, 92.0, 65.0, 67.0, 68.0], "value": 78.9, "unit": "score"}], "name": "kraken", "value": 1522.6000000000001, "alertThreshold": 2.0}]} 21:30:36 INFO - Return code: 0 21:30:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 21:30:36 ERROR - # TBPL SUCCESS # 21:30:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:30:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:30:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:30:36 INFO - 2016-05-19 21:30:36.597 screenresolution[1678:10325] starting screenresolution argv=screenresolution get 21:30:36 INFO - 2016-05-19 21:30:36.599 screenresolution[1678:10325] Display 0: 1600x1200x32@60 21:30:36 INFO - 2016-05-19 21:30:36.605 screenresolution[1679:10328] starting screenresolution argv=screenresolution list 21:30:36 INFO - Available Modes on Display 0 21:30:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:30:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:30:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:30:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:30:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:30:36 INFO - Intel Iris: 21:30:36 INFO - Chipset Model: Intel Iris 21:30:36 INFO - Type: GPU 21:30:36 INFO - Bus: Built-In 21:30:36 INFO - VRAM (Dynamic, Max): 1536 MB 21:30:36 INFO - Vendor: Intel (0x8086) 21:30:36 INFO - Device ID: 0x0a2e 21:30:36 INFO - Revision ID: 0x0009 21:30:36 INFO - Displays: 21:30:36 INFO - Display: 21:30:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:30:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:30:36 INFO - Display Serial Number: 1600x1200 60 21:30:36 INFO - Main Display: Yes 21:30:36 INFO - Mirror: Off 21:30:36 INFO - Online: Yes 21:30:36 INFO - Rotation: Supported 21:30:36 INFO - Return code: 0 21:30:36 INFO - Running post-action listener: _resource_record_post_action 21:30:36 INFO - Running post-run listener: _resource_record_post_run 21:30:37 INFO - Total resource usage - Wall time: 1208s; CPU: 53.0%; Read bytes: 37824000; Write bytes: 656313856; Read time: 396; Write time: 2115 21:30:37 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 354576896; Write bytes: 356834816; Read time: 27739; Write time: 795 21:30:37 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 36034048; Write bytes: 298631168; Read time: 376; Write time: 1318 21:30:37 INFO - Running post-run listener: _upload_blobber_files 21:30:37 INFO - Blob upload gear active. 21:30:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:30:37 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 21:30:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:30:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:30:38 INFO - (blobuploader) - INFO - Open directory for files ... 21:30:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:30:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:30:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:30:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:30:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:30:39 INFO - (blobuploader) - INFO - Done attempting. 21:30:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:30:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:30:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:30:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:30:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:30:40 INFO - (blobuploader) - INFO - Done attempting. 21:30:40 INFO - (blobuploader) - INFO - Iteration through files over. 21:30:40 INFO - Return code: 0 21:30:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:30:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:30:40 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f44f45f0887c528a577415077dd4473ae00e86b3ab2aea9810ee85bc279266783dc8f5f230634c055afae2aa8dcf3c7d94e82c45036dd26076b5ba344659563b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bc89440f070ca731fd0142856afd316c7204f51ab4c6204a544163a5cc7413c7683ad87ca5c1378fbeb53ed0f6724f8f38edba1c8c436c92e3191c7b84099fea"} 21:30:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:30:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:30:40 INFO - Contents: 21:30:40 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f44f45f0887c528a577415077dd4473ae00e86b3ab2aea9810ee85bc279266783dc8f5f230634c055afae2aa8dcf3c7d94e82c45036dd26076b5ba344659563b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bc89440f070ca731fd0142856afd316c7204f51ab4c6204a544163a5cc7413c7683ad87ca5c1378fbeb53ed0f6724f8f38edba1c8c436c92e3191c7b84099fea"} 21:30:40 INFO - Running post-run listener: copy_logs_to_upload_dir 21:30:40 INFO - Copying logs to upload dir... 21:30:40 INFO - mkdir: /builds/slave/test/build/upload/logs 21:30:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1332.996396 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 13 secs) (at 2016-05-19 21:30:40.573114) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-19 21:30:40.578780) ========= 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.qskAUs0bnP/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.VBofgv6Dtn/Listeners TMPDIR=/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f44f45f0887c528a577415077dd4473ae00e86b3ab2aea9810ee85bc279266783dc8f5f230634c055afae2aa8dcf3c7d94e82c45036dd26076b5ba344659563b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bc89440f070ca731fd0142856afd316c7204f51ab4c6204a544163a5cc7413c7683ad87ca5c1378fbeb53ed0f6724f8f38edba1c8c436c92e3191c7b84099fea"} build_url:https://queue.taskcluster.net/v1/task/L-vWqz3XQH2ICR74QtoRog/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011177 build_url: 'https://queue.taskcluster.net/v1/task/L-vWqz3XQH2ICR74QtoRog/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f44f45f0887c528a577415077dd4473ae00e86b3ab2aea9810ee85bc279266783dc8f5f230634c055afae2aa8dcf3c7d94e82c45036dd26076b5ba344659563b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bc89440f070ca731fd0142856afd316c7204f51ab4c6204a544163a5cc7413c7683ad87ca5c1378fbeb53ed0f6724f8f38edba1c8c436c92e3191c7b84099fea"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-19 21:30:40.608451) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 21:30:40.608776) ========= 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.qskAUs0bnP/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.VBofgv6Dtn/Listeners TMPDIR=/var/folders/0y/p7m66bps5fxgrhq27drfxb6r00000w/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.005278 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 21:30:40.657502) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-19 21:30:40.657829) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-19 21:30:40.658246) ========= ========= Total master_lag: 0.16 =========