builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0108 starttime: 1460720869.16 results: success (0) buildid: 20160415024941 builduid: 0bffa32634544aafa9c4948ff5e4599c revision: b7772a6288f8b1011e1dfc8629ced4f401e47c9c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-15 04:47:49.161932) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-15 04:47:49.162368) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-15 04:47:49.162640) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-15 04:47:49.681290) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-15 04:47:49.681628) ========= 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.FZOqlnWUfg/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.QZNBpnQxcO/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-15 04:47:49-- 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% 165M=0s 2016-04-15 04:47:50 (165 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.125455 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-15 04:47:49.826251) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-15 04:47:49.826574) ========= 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.FZOqlnWUfg/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.QZNBpnQxcO/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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.026891 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-15 04:47:49.874818) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-15 04:47:49.875167) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev b7772a6288f8b1011e1dfc8629ced4f401e47c9c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev b7772a6288f8b1011e1dfc8629ced4f401e47c9c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FZOqlnWUfg/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.QZNBpnQxcO/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-15 04:47:50,194 truncating revision to first 12 chars 2016-04-15 04:47:50,194 Setting DEBUG logging. 2016-04-15 04:47:50,194 attempt 1/10 2016-04-15 04:47:50,194 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/b7772a6288f8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-15 04:47:51,884 unpacking tar archive at: fx-team-b7772a6288f8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.927233 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-15 04:47:51.818837) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-15 04:47:51.822156) ========= script_repo_revision: b7772a6288f8b1011e1dfc8629ced4f401e47c9c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-15 04:47:51.822548) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-15 04:47:51.822807) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-15 04:47:51.841575) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-04-15 04:47:51.841850) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.FZOqlnWUfg/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.QZNBpnQxcO/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 04:47:52 INFO - MultiFileLogger online at 20160415 04:47:52 in /builds/slave/test 04:47:52 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 04:47:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:47:52 INFO - {'append_to_log': False, 04:47:52 INFO - 'base_work_dir': '/builds/slave/test', 04:47:52 INFO - 'blob_upload_branch': 'Fx-Team', 04:47:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:47:52 INFO - 'branch': 'Fx-Team', 04:47:52 INFO - 'buildbot_json_path': 'buildprops.json', 04:47:52 INFO - 'config_files': ('talos/mac_config.py',), 04:47:52 INFO - 'default_actions': ('clobber', 04:47:52 INFO - 'read-buildbot-config', 04:47:52 INFO - 'download-and-extract', 04:47:52 INFO - 'populate-webroot', 04:47:52 INFO - 'create-virtualenv', 04:47:52 INFO - 'install', 04:47:52 INFO - 'run-tests'), 04:47:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:47:52 INFO - 'download_minidump_stackwalk': True, 04:47:52 INFO - 'download_symbols': 'ondemand', 04:47:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:47:52 INFO - 'tooltool.py': '/tools/tooltool.py', 04:47:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:47:52 INFO - '/tools/misc-python/virtualenv.py')}, 04:47:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:47:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:47:52 INFO - 'installer_path': 'installer.exe', 04:47:52 INFO - 'log_level': 'info', 04:47:52 INFO - 'log_name': 'talos', 04:47:52 INFO - 'log_to_console': True, 04:47:52 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:47:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:47:52 INFO - 'opt_config_files': (), 04:47:52 INFO - 'pip_index': False, 04:47:52 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:47:52 INFO - 'cmd': ('bash', 04:47:52 INFO - '-c', 04:47:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:47:52 INFO - 'enabled': True, 04:47:52 INFO - 'halt_on_failure': False, 04:47:52 INFO - 'name': 'check_screen_resolution'},), 04:47:52 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:47:52 INFO - 'cmd': ('bash', 04:47:52 INFO - '-c', 04:47:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:47:52 INFO - 'enabled': True, 04:47:52 INFO - 'halt_on_failure': False, 04:47:52 INFO - 'name': 'check_screen_resolution'},), 04:47:52 INFO - 'run_cmd_checks_enabled': True, 04:47:52 INFO - 'sps_profile': False, 04:47:52 INFO - 'sps_profile_interval': 0, 04:47:52 INFO - 'suite': 'dromaeojs-e10s', 04:47:52 INFO - 'system_bits': '32', 04:47:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:47:52 INFO - 'title': 't-yosemite-r7-0108', 04:47:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:47:52 INFO - 'use_talos_json': True, 04:47:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:47:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:47:52 INFO - 'work_dir': 'build'} 04:47:52 INFO - ##### 04:47:52 INFO - ##### Running clobber step. 04:47:52 INFO - ##### 04:47:52 INFO - Running pre-action listener: _resource_record_pre_action 04:47:52 INFO - Running main action method: clobber 04:47:52 INFO - rmtree: /builds/slave/test/build 04:47:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:47:53 INFO - Running post-action listener: _resource_record_post_action 04:47:53 INFO - ##### 04:47:53 INFO - ##### Running read-buildbot-config step. 04:47:53 INFO - ##### 04:47:53 INFO - Running pre-action listener: _resource_record_pre_action 04:47:53 INFO - Running main action method: read_buildbot_config 04:47:53 INFO - Using buildbot properties: 04:47:53 INFO - { 04:47:53 INFO - "project": "", 04:47:53 INFO - "product": "firefox", 04:47:53 INFO - "script_repo_revision": "production", 04:47:53 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 04:47:53 INFO - "repository": "", 04:47:53 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 04:47:53 INFO - "buildid": "20160415024941", 04:47:53 INFO - "pgo_build": "False", 04:47:53 INFO - "basedir": "/builds/slave/test", 04:47:53 INFO - "buildnumber": 694, 04:47:53 INFO - "slavename": "t-yosemite-r7-0108", 04:47:53 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 04:47:53 INFO - "platform": "yosemite_r7", 04:47:53 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 04:47:53 INFO - "branch": "fx-team", 04:47:53 INFO - "repo_path": "integration/fx-team", 04:47:53 INFO - "revision": "b7772a6288f8b1011e1dfc8629ced4f401e47c9c", 04:47:53 INFO - "stage_platform": "macosx64", 04:47:53 INFO - "builduid": "0bffa32634544aafa9c4948ff5e4599c", 04:47:53 INFO - "slavebuilddir": "test" 04:47:53 INFO - } 04:47:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/PlPF6wH1SqurAMmEVhKS_g/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 04:47:53 INFO - Running post-action listener: _resource_record_post_action 04:47:53 INFO - ##### 04:47:53 INFO - ##### Running download-and-extract step. 04:47:53 INFO - ##### 04:47:53 INFO - Running pre-action listener: _resource_record_pre_action 04:47:53 INFO - Running main action method: download_and_extract 04:47:53 INFO - mkdir: /builds/slave/test/build/tests 04:47:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:53 INFO - https://queue.taskcluster.net/v1/task/PlPF6wH1SqurAMmEVhKS_g/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 04:47:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PlPF6wH1SqurAMmEVhKS_g/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 04:47:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PlPF6wH1SqurAMmEVhKS_g/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 04:47:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PlPF6wH1SqurAMmEVhKS_g/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 04:47:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PlPF6wH1SqurAMmEVhKS_g/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 04:47:55 INFO - Downloaded 1199 bytes. 04:47:55 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 04:47:55 INFO - Using the following test package requirements: 04:47:55 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 04:47:55 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:47:55 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 04:47:55 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:47:55 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 04:47:55 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:47:55 INFO - u'jsshell-mac.zip'], 04:47:55 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:47:55 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 04:47:55 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 04:47:55 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:47:55 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 04:47:55 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:47:55 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 04:47:55 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:47:55 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 04:47:55 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 04:47:55 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 04:47:55 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:47:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:47:55 INFO - https://queue.taskcluster.net/v1/task/PlPF6wH1SqurAMmEVhKS_g/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:47:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PlPF6wH1SqurAMmEVhKS_g/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:47:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PlPF6wH1SqurAMmEVhKS_g/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:47:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PlPF6wH1SqurAMmEVhKS_g/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:47:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PlPF6wH1SqurAMmEVhKS_g/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:47:57 INFO - Downloaded 18031158 bytes. 04:47:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:47:57 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:48:00 INFO - Return code: 0 04:48:00 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:48:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:00 INFO - https://queue.taskcluster.net/v1/task/PlPF6wH1SqurAMmEVhKS_g/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:48:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PlPF6wH1SqurAMmEVhKS_g/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:48:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PlPF6wH1SqurAMmEVhKS_g/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:48:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PlPF6wH1SqurAMmEVhKS_g/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 04:48:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PlPF6wH1SqurAMmEVhKS_g/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:48:01 INFO - Downloaded 18031158 bytes. 04:48:01 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:48:01 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:48:05 INFO - Return code: 0 04:48:05 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')]} 04:48:05 INFO - https://queue.taskcluster.net/v1/task/PlPF6wH1SqurAMmEVhKS_g/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 04:48:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PlPF6wH1SqurAMmEVhKS_g/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 04:48:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PlPF6wH1SqurAMmEVhKS_g/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 04:48:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PlPF6wH1SqurAMmEVhKS_g/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 04:48:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PlPF6wH1SqurAMmEVhKS_g/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:48:06 INFO - Downloaded 10841076 bytes. 04:48:06 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:48:06 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 04:48:06 INFO - Return code: 0 04:48:06 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')]} 04:48:06 INFO - https://queue.taskcluster.net/v1/task/PlPF6wH1SqurAMmEVhKS_g/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 04:48:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PlPF6wH1SqurAMmEVhKS_g/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 04:48:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PlPF6wH1SqurAMmEVhKS_g/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 04:48:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PlPF6wH1SqurAMmEVhKS_g/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 04:48:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/PlPF6wH1SqurAMmEVhKS_g/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 04:48:09 INFO - Downloaded 109834665 bytes. 04:48:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PlPF6wH1SqurAMmEVhKS_g/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 04:48:09 INFO - mkdir: /builds/slave/test/properties 04:48:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:48:09 INFO - Writing to file /builds/slave/test/properties/build_url 04:48:09 INFO - Contents: 04:48:09 INFO - build_url:https://queue.taskcluster.net/v1/task/PlPF6wH1SqurAMmEVhKS_g/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 04:48:10 INFO - Running post-action listener: _resource_record_post_action 04:48:10 INFO - Running post-action listener: set_extra_try_arguments 04:48:10 INFO - ##### 04:48:10 INFO - ##### Running populate-webroot step. 04:48:10 INFO - ##### 04:48:10 INFO - Running pre-action listener: _resource_record_pre_action 04:48:10 INFO - Running main action method: populate_webroot 04:48:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:48:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:48:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:48:10 INFO - '--tppagecycles', 04:48:10 INFO - '7'], 04:48:10 INFO - 'tests': ['tsvgm']}}, 04:48:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:48:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:48:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:48:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:48:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:48:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:48:10 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:48:10 INFO - 'talos_options': ['--disable-e10s'], 04:48:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:48:10 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:48:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:48:10 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:48:10 INFO - 'talos_options': ['--disable-e10s'], 04:48:10 INFO - 'tests': ['damp', 'tps']}, 04:48:10 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:48:10 INFO - 'tests': ['damp', 'tps']}, 04:48:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:48:10 INFO - 'tests': ['dromaeo_dom']}, 04:48:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:48:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:48:10 INFO - 'tests': ['a11yr', 04:48:10 INFO - 'ts_paint', 04:48:10 INFO - 'tpaint', 04:48:10 INFO - 'sessionrestore', 04:48:10 INFO - 'sessionrestore_no_auto_restore', 04:48:10 INFO - 'tabpaint']}, 04:48:10 INFO - 'other-e10s': {'tests': ['a11yr', 04:48:10 INFO - 'ts_paint', 04:48:10 INFO - 'tpaint', 04:48:10 INFO - 'sessionrestore', 04:48:10 INFO - 'sessionrestore_no_auto_restore', 04:48:10 INFO - 'tabpaint']}, 04:48:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:48:10 INFO - 'ts_paint', 04:48:10 INFO - 'tpaint', 04:48:10 INFO - 'sessionrestore', 04:48:10 INFO - 'sessionrestore_no_auto_restore', 04:48:10 INFO - 'tabpaint']}, 04:48:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:48:10 INFO - 'ts_paint', 04:48:10 INFO - 'tpaint', 04:48:10 INFO - 'sessionrestore', 04:48:10 INFO - 'sessionrestore_no_auto_restore', 04:48:10 INFO - 'tabpaint']}, 04:48:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:48:10 INFO - 'tests': ['a11yr', 04:48:10 INFO - 'ts_paint', 04:48:10 INFO - 'tpaint', 04:48:10 INFO - 'sessionrestore', 04:48:10 INFO - 'sessionrestore_no_auto_restore', 04:48:10 INFO - 'tabpaint']}, 04:48:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:48:10 INFO - 'tests': ['a11yr', 04:48:10 INFO - 'ts_paint', 04:48:10 INFO - 'tpaint', 04:48:10 INFO - 'sessionrestore', 04:48:10 INFO - 'sessionrestore_no_auto_restore', 04:48:10 INFO - 'tabpaint']}, 04:48:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:48:10 INFO - 'tests': ['tsvgx', 04:48:10 INFO - 'tsvgr_opacity', 04:48:10 INFO - 'tart', 04:48:10 INFO - 'tscrollx', 04:48:10 INFO - 'cart']}, 04:48:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:48:10 INFO - 'tsvgr_opacity', 04:48:10 INFO - 'tart', 04:48:10 INFO - 'tscrollx', 04:48:10 INFO - 'cart']}, 04:48:10 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:48:10 INFO - 'talos_options': ['--disable-e10s'], 04:48:10 INFO - 'tests': ['tp5o']}, 04:48:10 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:48:10 INFO - 'tests': ['tp5o']}, 04:48:10 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:48:10 INFO - 'talos_options': ['--disable-e10s', 04:48:10 INFO - '--xperf_path', 04:48:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:48:10 INFO - 'tests': ['tp5n']}, 04:48:10 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:48:10 INFO - 'talos_options': ['--xperf_path', 04:48:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:48:10 INFO - 'tests': ['tp5n']}}, 04:48:10 INFO - 'talos.zip': {'path': '', 04:48:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:48:10 INFO - Running post-action listener: _resource_record_post_action 04:48:10 INFO - ##### 04:48:10 INFO - ##### Running create-virtualenv step. 04:48:10 INFO - ##### 04:48:10 INFO - Running pre-action listener: _resource_record_pre_action 04:48:10 INFO - Running main action method: create_virtualenv 04:48:10 INFO - Creating virtualenv /builds/slave/test/build/venv 04:48:10 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 04:48:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:48:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:48:10 INFO - Using real prefix '/tools/python27' 04:48:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:48:11 INFO - Installing distribute.............................................................................................................................................................................................done. 04:48:14 INFO - Installing pip.................done. 04:48:14 INFO - Return code: 0 04:48:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:48: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')]} 04:48:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48: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')]} 04:48:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:14 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 0x10f129a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f139420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe478da5d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efc8e00>, '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 0x10ee31db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10efc3b20>, '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.FZOqlnWUfg/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.QZNBpnQxcO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 04:48:14 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 04:48:14 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 04:48:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FZOqlnWUfg/Render', 04:48:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:48:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:48:14 INFO - 'HOME': '/Users/cltbld', 04:48:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:48:14 INFO - 'LOGNAME': 'cltbld', 04:48:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:14 INFO - 'MOZ_NO_REMOTE': '1', 04:48:14 INFO - 'NO_EM_RESTART': '1', 04:48:14 INFO - 'PAGER': '/bin/cat', 04:48:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:48:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:14 INFO - 'PWD': '/builds/slave/test', 04:48:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:48:14 INFO - 'SHELL': '/bin/bash', 04:48:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QZNBpnQxcO/Listeners', 04:48:14 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 04:48:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:48:14 INFO - 'USER': 'cltbld', 04:48:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:48:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:48:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:14 INFO - 'XPC_FLAGS': '0x0', 04:48:14 INFO - 'XPC_SERVICE_NAME': '0', 04:48:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:48:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:14 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 04:48:14 INFO - Cleaning up... 04:48:14 INFO - Return code: 0 04:48:14 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:48: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')]} 04:48:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48: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')]} 04:48:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:14 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 0x10f129a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f139420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe478da5d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efc8e00>, '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 0x10ee31db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10efc3b20>, '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.FZOqlnWUfg/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.QZNBpnQxcO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 04:48:14 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 04:48:14 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 04:48:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FZOqlnWUfg/Render', 04:48:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:48:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:48:14 INFO - 'HOME': '/Users/cltbld', 04:48:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:48:14 INFO - 'LOGNAME': 'cltbld', 04:48:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:14 INFO - 'MOZ_NO_REMOTE': '1', 04:48:14 INFO - 'NO_EM_RESTART': '1', 04:48:14 INFO - 'PAGER': '/bin/cat', 04:48:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:48:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:14 INFO - 'PWD': '/builds/slave/test', 04:48:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:48:14 INFO - 'SHELL': '/bin/bash', 04:48:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QZNBpnQxcO/Listeners', 04:48:14 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 04:48:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:48:14 INFO - 'USER': 'cltbld', 04:48:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:48:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:48:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:14 INFO - 'XPC_FLAGS': '0x0', 04:48:14 INFO - 'XPC_SERVICE_NAME': '0', 04:48:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:48:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:14 INFO - Downloading/unpacking psutil>=0.7.1 04:48:14 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 04:48:14 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 04:48:14 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 04:48:14 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 04:48:14 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 04:48:14 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 04:48:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:48:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:48:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:48:17 INFO - Installing collected packages: psutil 04:48:17 INFO - Running setup.py install for psutil 04:48:17 INFO - building 'psutil._psutil_osx' extension 04:48:17 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 04:48:18 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 04:48:18 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 04:48:18 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 04:48:18 INFO - building 'psutil._psutil_posix' extension 04:48:18 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 04:48:18 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:48:18 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:48:18 INFO - ^ 04:48:18 INFO - 1 warning generated. 04:48:18 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 04:48:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:48:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:48:19 INFO - Successfully installed psutil 04:48:19 INFO - Cleaning up... 04:48:19 INFO - Return code: 0 04:48:19 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:48: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')]} 04:48:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48: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')]} 04:48:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48: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', '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 0x10f129a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f139420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe478da5d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efc8e00>, '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 0x10ee31db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10efc3b20>, '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.FZOqlnWUfg/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.QZNBpnQxcO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 04:48: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 04:48: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 mozsystemmonitor==0.0.0 04:48:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FZOqlnWUfg/Render', 04:48:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:48:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:48:19 INFO - 'HOME': '/Users/cltbld', 04:48:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:48:19 INFO - 'LOGNAME': 'cltbld', 04:48:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:19 INFO - 'MOZ_NO_REMOTE': '1', 04:48:19 INFO - 'NO_EM_RESTART': '1', 04:48:19 INFO - 'PAGER': '/bin/cat', 04:48:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:48:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:19 INFO - 'PWD': '/builds/slave/test', 04:48:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:48:19 INFO - 'SHELL': '/bin/bash', 04:48:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QZNBpnQxcO/Listeners', 04:48:19 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 04:48:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:48:19 INFO - 'USER': 'cltbld', 04:48:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:48:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:48:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:19 INFO - 'XPC_FLAGS': '0x0', 04:48:19 INFO - 'XPC_SERVICE_NAME': '0', 04:48:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:48:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:19 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:48: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 04:48: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 04:48: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 04:48: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 04:48: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 04:48: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 04:48:21 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:48:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:48:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 04:48:22 INFO - Installing collected packages: mozsystemmonitor 04:48:22 INFO - Running setup.py install for mozsystemmonitor 04:48:22 INFO - Successfully installed mozsystemmonitor 04:48:22 INFO - Cleaning up... 04:48:22 INFO - Return code: 0 04:48:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:48:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f129a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f139420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe478da5d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efc8e00>, '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 0x10ee31db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10efc3b20>, '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.FZOqlnWUfg/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.QZNBpnQxcO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 04:48:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:48:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:48:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FZOqlnWUfg/Render', 04:48:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:48:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:48:22 INFO - 'HOME': '/Users/cltbld', 04:48:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:48:22 INFO - 'LOGNAME': 'cltbld', 04:48:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:22 INFO - 'MOZ_NO_REMOTE': '1', 04:48:22 INFO - 'NO_EM_RESTART': '1', 04:48:22 INFO - 'PAGER': '/bin/cat', 04:48:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:48:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:22 INFO - 'PWD': '/builds/slave/test', 04:48:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:48:22 INFO - 'SHELL': '/bin/bash', 04:48:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QZNBpnQxcO/Listeners', 04:48:22 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 04:48:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:48:22 INFO - 'USER': 'cltbld', 04:48:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:48:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:48:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:22 INFO - 'XPC_FLAGS': '0x0', 04:48:22 INFO - 'XPC_SERVICE_NAME': '0', 04:48:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:48:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:22 INFO - Downloading/unpacking blobuploader==1.2.4 04:48: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 04:48: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 04:48: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 04:48: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 04:48: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 04:48: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 04:48:24 INFO - Downloading blobuploader-1.2.4.tar.gz 04:48:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:48:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:48:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:48:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:48: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 04:48: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 04:48: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 04:48: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 04:48: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 04:48: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 04:48:26 INFO - Downloading docopt-0.6.1.tar.gz 04:48:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:48:26 INFO - Installing collected packages: blobuploader, requests, docopt 04:48:26 INFO - Running setup.py install for blobuploader 04:48:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:48:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:48:26 INFO - Running setup.py install for requests 04:48:26 INFO - Running setup.py install for docopt 04:48:26 INFO - Successfully installed blobuploader requests docopt 04:48:26 INFO - Cleaning up... 04:48:27 INFO - Return code: 0 04:48:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:48:27 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')]} 04:48:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:27 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')]} 04:48:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:27 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 0x10f129a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f139420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe478da5d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efc8e00>, '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 0x10ee31db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10efc3b20>, '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.FZOqlnWUfg/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.QZNBpnQxcO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 04:48:27 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 04:48:27 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 04:48:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FZOqlnWUfg/Render', 04:48:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:48:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:48:27 INFO - 'HOME': '/Users/cltbld', 04:48:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:48:27 INFO - 'LOGNAME': 'cltbld', 04:48:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:27 INFO - 'MOZ_NO_REMOTE': '1', 04:48:27 INFO - 'NO_EM_RESTART': '1', 04:48:27 INFO - 'PAGER': '/bin/cat', 04:48:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:48:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:27 INFO - 'PWD': '/builds/slave/test', 04:48:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:48:27 INFO - 'SHELL': '/bin/bash', 04:48:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QZNBpnQxcO/Listeners', 04:48:27 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 04:48:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:48:27 INFO - 'USER': 'cltbld', 04:48:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:48:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:48:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:27 INFO - 'XPC_FLAGS': '0x0', 04:48:27 INFO - 'XPC_SERVICE_NAME': '0', 04:48:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:48:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:48:27 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-mAo6jQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:48:27 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-3VtEts-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:48:27 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-vBi3Y0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:48:27 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-zXf2p1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:48:27 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-FygmLm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:48:27 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-h4ECKv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:48:27 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-v3GWNW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:48:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:48:28 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-X9h_XY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:48:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:48:28 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-Ad9myo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:48:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:48:28 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-QCSRqH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:48:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:48:28 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-X5Eg7e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:48:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:48:28 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-t4xnxx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:48:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:48:28 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-seAb2C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:48:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:48:28 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-HdnfRb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:48:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:48:28 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-lroOpE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:48:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:48:28 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-6gAyS2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:48:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:48:28 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-2HQK_n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:48:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:48:29 INFO - Running setup.py install for manifestparser 04:48:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:48:29 INFO - Running setup.py install for mozcrash 04:48:29 INFO - Running setup.py install for mozdebug 04:48:29 INFO - Running setup.py install for mozdevice 04:48:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:48:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:48:29 INFO - Running setup.py install for mozfile 04:48:29 INFO - Running setup.py install for mozhttpd 04:48:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:48:29 INFO - Running setup.py install for mozinfo 04:48:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:48:30 INFO - Running setup.py install for mozInstall 04:48:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:48:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:48:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:48:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:48:30 INFO - Running setup.py install for mozleak 04:48:30 INFO - Running setup.py install for mozlog 04:48:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:48:30 INFO - Running setup.py install for moznetwork 04:48:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:48:30 INFO - Running setup.py install for mozprocess 04:48:30 INFO - Running setup.py install for mozprofile 04:48:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:48:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:48:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:48:30 INFO - Running setup.py install for mozrunner 04:48:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:48:31 INFO - Running setup.py install for mozscreenshot 04:48:31 INFO - Running setup.py install for moztest 04:48:31 INFO - Running setup.py install for mozversion 04:48:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:48:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:48:31 INFO - Cleaning up... 04:48:31 INFO - Return code: 0 04:48:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:48: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')]} 04:48:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48: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')]} 04:48:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:31 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 0x10f129a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f139420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe478da5d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efc8e00>, '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 0x10ee31db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10efc3b20>, '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.FZOqlnWUfg/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.QZNBpnQxcO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 04:48:31 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 04:48:31 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 04:48:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FZOqlnWUfg/Render', 04:48:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:48:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:48:31 INFO - 'HOME': '/Users/cltbld', 04:48:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:48:31 INFO - 'LOGNAME': 'cltbld', 04:48:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:31 INFO - 'MOZ_NO_REMOTE': '1', 04:48:31 INFO - 'NO_EM_RESTART': '1', 04:48:31 INFO - 'PAGER': '/bin/cat', 04:48:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:48:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:31 INFO - 'PWD': '/builds/slave/test', 04:48:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:48:31 INFO - 'SHELL': '/bin/bash', 04:48:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QZNBpnQxcO/Listeners', 04:48:31 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 04:48:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:48:31 INFO - 'USER': 'cltbld', 04:48:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:48:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:48:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:31 INFO - 'XPC_FLAGS': '0x0', 04:48:31 INFO - 'XPC_SERVICE_NAME': '0', 04:48:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:48:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:48:31 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-dce01s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:48:31 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)) 04:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:48:31 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-gp9lcC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:48:32 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)) 04:48:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:48:32 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-lB__2q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:48:32 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)) 04:48:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:48:32 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-uHtivA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:48:32 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)) 04:48:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:48:32 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-MSzkcg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:48:32 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)) 04:48:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:48:32 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-n6VIBf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:48:32 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)) 04:48:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:48:32 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-B9AqGh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:48:32 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)) 04:48:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:48:32 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-uNgI8M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:48:32 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)) 04:48:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:48:32 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-32kKeh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:48:32 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)) 04:48:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:48:32 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-vld8bB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:48:32 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)) 04:48:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:48:32 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-6rcYOQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:48:33 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)) 04:48:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:48:33 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-HFoG2W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:48:33 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)) 04:48:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:48:33 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-8KxxgQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:48:33 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)) 04:48:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:48:33 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-hWpWJ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:48:33 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)) 04:48:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:48:33 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-9dUAX4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:48:33 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)) 04:48:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:48:33 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-iMnOnJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:48:33 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)) 04:48:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:48:33 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-V34sXZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:48:33 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)) 04:48:33 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)) 04:48:33 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)) 04:48:33 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)) 04:48:33 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)) 04:48:33 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)) 04:48:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:48:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:48:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:48:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:48:36 INFO - Downloading blessings-1.6.tar.gz 04:48:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:48:36 INFO - Installing collected packages: blessings 04:48:36 INFO - Running setup.py install for blessings 04:48:36 INFO - Successfully installed blessings 04:48:36 INFO - Cleaning up... 04:48:36 INFO - Return code: 0 04:48:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:48:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:48:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:48:36 INFO - Reading from file tmpfile_stdout 04:48:36 INFO - Current package versions: 04:48:36 INFO - blessings == 1.6 04:48:36 INFO - blobuploader == 1.2.4 04:48:36 INFO - docopt == 0.6.1 04:48:36 INFO - manifestparser == 1.1 04:48:36 INFO - mozInstall == 1.12 04:48:36 INFO - mozcrash == 0.17 04:48:36 INFO - mozdebug == 0.1 04:48:36 INFO - mozdevice == 0.48 04:48:36 INFO - mozfile == 1.2 04:48:36 INFO - mozhttpd == 0.7 04:48:36 INFO - mozinfo == 0.9 04:48:36 INFO - mozleak == 0.1 04:48:36 INFO - mozlog == 3.1 04:48:36 INFO - moznetwork == 0.27 04:48:36 INFO - mozprocess == 0.22 04:48:36 INFO - mozprofile == 0.28 04:48:36 INFO - mozrunner == 6.11 04:48:36 INFO - mozscreenshot == 0.1 04:48:36 INFO - mozsystemmonitor == 0.0 04:48:36 INFO - moztest == 0.7 04:48:36 INFO - mozversion == 1.4 04:48:36 INFO - psutil == 3.1.1 04:48:36 INFO - requests == 1.2.3 04:48:36 INFO - wsgiref == 0.1.2 04:48:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:48:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:36 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 0x10f129a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f139420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe478da5d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efc8e00>, '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 0x10ee31db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10efc3b20>, '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.FZOqlnWUfg/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.QZNBpnQxcO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 04:48:36 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 04:48:36 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 04:48:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FZOqlnWUfg/Render', 04:48:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:48:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:48:36 INFO - 'HOME': '/Users/cltbld', 04:48:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:48:36 INFO - 'LOGNAME': 'cltbld', 04:48:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:36 INFO - 'MOZ_NO_REMOTE': '1', 04:48:36 INFO - 'NO_EM_RESTART': '1', 04:48:36 INFO - 'PAGER': '/bin/cat', 04:48:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:48:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:36 INFO - 'PWD': '/builds/slave/test', 04:48:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:48:36 INFO - 'SHELL': '/bin/bash', 04:48:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QZNBpnQxcO/Listeners', 04:48:36 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 04:48:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:48:36 INFO - 'USER': 'cltbld', 04:48:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:48:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:48:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:36 INFO - 'XPC_FLAGS': '0x0', 04:48:36 INFO - 'XPC_SERVICE_NAME': '0', 04:48:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:48:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:37 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)) 04:48:37 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)) 04:48:37 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)) 04:48:37 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)) 04:48:37 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)) 04:48:37 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)) 04:48:37 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)) 04:48:37 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)) 04:48:37 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)) 04:48:37 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)) 04:48:37 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)) 04:48:37 INFO - Cleaning up... 04:48:37 INFO - Return code: 0 04:48:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:48:37 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')]} 04:48:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:37 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')]} 04:48:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:37 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 0x10f129a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f139420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe478da5d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efc8e00>, '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 0x10ee31db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10efc3b20>, '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.FZOqlnWUfg/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.QZNBpnQxcO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 04:48:37 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 04:48:37 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 04:48:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FZOqlnWUfg/Render', 04:48:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:48:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:48:37 INFO - 'HOME': '/Users/cltbld', 04:48:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:48:37 INFO - 'LOGNAME': 'cltbld', 04:48:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:37 INFO - 'MOZ_NO_REMOTE': '1', 04:48:37 INFO - 'NO_EM_RESTART': '1', 04:48:37 INFO - 'PAGER': '/bin/cat', 04:48:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:48:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:37 INFO - 'PWD': '/builds/slave/test', 04:48:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:48:37 INFO - 'SHELL': '/bin/bash', 04:48:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QZNBpnQxcO/Listeners', 04:48:37 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 04:48:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:48:37 INFO - 'USER': 'cltbld', 04:48:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:48:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:48:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:37 INFO - 'XPC_FLAGS': '0x0', 04:48:37 INFO - 'XPC_SERVICE_NAME': '0', 04:48:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:48:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:37 INFO - Downloading/unpacking jsonschema 04:48:37 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 04:48:37 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 04:48:37 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 04:48:37 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 04:48:37 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 04:48:37 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 04:48:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:48:39 INFO - Downloading/unpacking functools32 (from jsonschema) 04:48:39 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 04:48:39 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 04:48:39 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 04:48:39 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 04:48:39 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 04:48:39 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 04:48:40 INFO - Downloading functools32-3.2.3-2.tar.gz 04:48:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:48:40 INFO - warning: no files found matching '*.txt' 04:48:40 INFO - no previously-included directories found matching 'build' 04:48:40 INFO - no previously-included directories found matching 'dist' 04:48:40 INFO - no previously-included directories found matching '.git*' 04:48:40 INFO - Installing collected packages: jsonschema, functools32 04:48:40 INFO - Running setup.py install for functools32 04:48:40 INFO - warning: no files found matching '*.txt' 04:48:40 INFO - no previously-included directories found matching 'build' 04:48:40 INFO - no previously-included directories found matching 'dist' 04:48:40 INFO - no previously-included directories found matching '.git*' 04:48:40 INFO - Successfully installed jsonschema functools32 04:48:40 INFO - Cleaning up... 04:48:40 INFO - Return code: 0 04:48:40 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:48:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:48:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:48:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:48:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:48:40 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 0x10f129a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f139420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe478da5d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10efc8e00>, '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 0x10ee31db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10efc3b20>, '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.FZOqlnWUfg/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.QZNBpnQxcO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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 04:48:40 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 04:48:40 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 04:48:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FZOqlnWUfg/Render', 04:48:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:48:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:48:40 INFO - 'HOME': '/Users/cltbld', 04:48:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:48:40 INFO - 'LOGNAME': 'cltbld', 04:48:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:48:40 INFO - 'MOZ_NO_REMOTE': '1', 04:48:40 INFO - 'NO_EM_RESTART': '1', 04:48:40 INFO - 'PAGER': '/bin/cat', 04:48:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:48:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:48:40 INFO - 'PWD': '/builds/slave/test', 04:48:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:48:40 INFO - 'SHELL': '/bin/bash', 04:48:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QZNBpnQxcO/Listeners', 04:48:40 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 04:48:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:48:40 INFO - 'USER': 'cltbld', 04:48:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:48:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:48:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:48:40 INFO - 'XPC_FLAGS': '0x0', 04:48:40 INFO - 'XPC_SERVICE_NAME': '0', 04:48:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:48:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:48:40 INFO - Downloading/unpacking flake8 04:48:40 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 04:48:40 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 04:48:40 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 04:48:40 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 04:48:40 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 04:48:40 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 04:48:43 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:48:43 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:48:43 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 04:48:43 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 04:48:43 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 04:48:43 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 04:48:43 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 04:48:43 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 04:48:43 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:48:43 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:48:43 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 04:48:43 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 04:48:43 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 04:48:43 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 04:48:43 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 04:48:43 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 04:48:43 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:48:43 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 04:48:43 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 04:48:43 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 04:48:43 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 04:48:43 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 04:48:43 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 04:48:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:48:44 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:48:44 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:48:44 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:48:44 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:48:44 INFO - no previously-included directories found matching 'docs/_build' 04:48:44 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:48:44 INFO - Running setup.py install for pep8 04:48:44 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:48:44 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:48:44 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:48:44 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:48:44 INFO - no previously-included directories found matching 'docs/_build' 04:48:44 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:48:44 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:48:44 INFO - Cleaning up... 04:48:44 INFO - Return code: 0 04:48:44 INFO - Running post-action listener: _resource_record_post_action 04:48:44 INFO - Running post-action listener: _start_resource_monitoring 04:48:44 INFO - Starting resource monitoring. 04:48:44 INFO - ##### 04:48:44 INFO - ##### Running install step. 04:48:44 INFO - ##### 04:48:44 INFO - Running pre-action listener: _resource_record_pre_action 04:48:44 INFO - Running main action method: install 04:48:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:48:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:48:45 INFO - Reading from file tmpfile_stdout 04:48:45 INFO - Detecting whether we're running mozinstall >=1.0... 04:48:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:48:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:48:45 INFO - Reading from file tmpfile_stdout 04:48:45 INFO - Output received: 04:48:45 INFO - Usage: mozinstall [options] installer 04:48:45 INFO - Options: 04:48:45 INFO - -h, --help show this help message and exit 04:48:45 INFO - -d DEST, --destination=DEST 04:48:45 INFO - Directory to install application into. [default: 04:48:45 INFO - "/builds/slave/test"] 04:48:45 INFO - --app=APP Application being installed. [default: firefox] 04:48:45 INFO - mkdir: /builds/slave/test/build/application 04:48:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:48:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:49:18 INFO - Reading from file tmpfile_stdout 04:49:18 INFO - Output received: 04:49:18 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:49:18 INFO - Running post-action listener: _resource_record_post_action 04:49:18 INFO - ##### 04:49:18 INFO - ##### Running run-tests step. 04:49:18 INFO - ##### 04:49:18 INFO - Running pre-action listener: _resource_record_pre_action 04:49:18 INFO - Running main action method: run_tests 04:49:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:49:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:49:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:49:18 INFO - 2016-04-15 04:49:18.950 screenresolution[1641:7548] starting screenresolution argv=screenresolution get 04:49:18 INFO - 2016-04-15 04:49:18.953 screenresolution[1641:7548] Display 0: 1600x1200x32@60 04:49:18 INFO - 2016-04-15 04:49:18.959 screenresolution[1642:7550] starting screenresolution argv=screenresolution list 04:49:18 INFO - Available Modes on Display 0 04:49:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:49:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:49:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:49:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:49:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:49:19 INFO - Intel Iris: 04:49:19 INFO - Chipset Model: Intel Iris 04:49:19 INFO - Type: GPU 04:49:19 INFO - Bus: Built-In 04:49:19 INFO - VRAM (Dynamic, Max): 1536 MB 04:49:19 INFO - Vendor: Intel (0x8086) 04:49:19 INFO - Device ID: 0x0a2e 04:49:19 INFO - Revision ID: 0x0009 04:49:19 INFO - Displays: 04:49:19 INFO - Display: 04:49:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:49:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:49:19 INFO - Display Serial Number: 1600x1200 60 04:49:19 INFO - Main Display: Yes 04:49:19 INFO - Mirror: Off 04:49:19 INFO - Online: Yes 04:49:19 INFO - Rotation: Supported 04:49:19 INFO - Return code: 0 04:49:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:49:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:49:19 INFO - Python 2.7.3 04:49:19 INFO - Return code: 0 04:49:19 INFO - grabbing minidump binary from tooltool 04:49: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')]} 04:49:19 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 0x10efc8e00>, '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 0x10ee31db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10efc3b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:49:19 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 04:49:19 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 04:49:19 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:49:19 INFO - Return code: 0 04:49:19 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:49:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:49:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:49:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:49:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:49:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:49:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:49:20 INFO - Return code: 0 04:49:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PlPF6wH1SqurAMmEVhKS_g/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0108', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 04:49:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PlPF6wH1SqurAMmEVhKS_g/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0108 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 04:49:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.FZOqlnWUfg/Render', 04:49:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:49:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:49:20 INFO - 'HOME': '/Users/cltbld', 04:49:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:49:20 INFO - 'LOGNAME': 'cltbld', 04:49:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:49:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:49:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:49:20 INFO - 'MOZ_NO_REMOTE': '1', 04:49:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:49:20 INFO - 'NO_EM_RESTART': '1', 04:49:20 INFO - 'PAGER': '/bin/cat', 04:49:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:49:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:49:20 INFO - 'PWD': '/builds/slave/test', 04:49:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:49:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:49:20 INFO - 'SHELL': '/bin/bash', 04:49:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QZNBpnQxcO/Listeners', 04:49:20 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 04:49:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:49:20 INFO - 'USER': 'cltbld', 04:49:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:49:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:49:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:49:20 INFO - 'XPC_FLAGS': '0x0', 04:49:20 INFO - 'XPC_SERVICE_NAME': '0', 04:49:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:49:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PlPF6wH1SqurAMmEVhKS_g/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0108', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 04:49:20 INFO - mozversion application_buildid: 20160415024941 04:49:20 INFO - mozversion application_changeset: b7772a6288f8b1011e1dfc8629ced4f401e47c9c 04:49:20 INFO - mozversion application_display_name: Nightly 04:49:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:49:20 INFO - mozversion application_name: Firefox 04:49:20 INFO - mozversion application_remotingname: firefox 04:49:20 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 04:49:20 INFO - mozversion application_vendor: Mozilla 04:49:20 INFO - mozversion application_version: 48.0a1 04:49:20 INFO - mozversion platform_buildid: 20160415024941 04:49:20 INFO - mozversion platform_changeset: b7772a6288f8b1011e1dfc8629ced4f401e47c9c 04:49:20 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 04:49:20 INFO - mozversion platform_version: 48.0a1 04:49:20 INFO - using testdate: 1460720960 04:49:20 INFO - actual date: 1460720960 04:49:20 INFO - starting webserver on 'localhost:49271' 04:49:20 INFO - SUITE-START | Running 2 tests 04:49:20 INFO - TEST-START | dromaeo_css 04:49:20 INFO - operating with platform_type : mac_ 04:49:20 INFO - Initialising browser for dromaeo_css test... 04:49:20 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/tmpGJkyGr/profile http://localhost:49271/getInfo.html) 04:49:23 INFO - PROCESS | 1660 | JavaScript error: jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/omni.ja!/components/Weave.js, line 13: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIXPCComponents_Utils.import] 04:49:23 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 04:49:23 INFO - PROCESS | 1660 | colorDepth:24 04:49:23 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 04:49:23 INFO - PROCESS | 1660 | __metrics 04:49:23 ERROR - PROCESS | 1660 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 04:49:24 INFO - TEST-INFO | 1660: exit 0 04:49:24 INFO - Browser initialized. 04:49:24 INFO - Running cycle 1/1 for dromaeo_css test... 04:49:24 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/tmpGJkyGr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:49:36 INFO - PROCESS | 1663 | RSS: Main: 247812096 04:49:36 INFO - PROCESS | 1663 | 04:50:25 INFO - PROCESS | 1663 | 1460721025923 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] 04:52:37 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 04:52:38 INFO - PROCESS | 1663 | RSS: Main: 260210688 04:52:38 INFO - PROCESS | 1663 | 04:55:40 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 04:55:41 INFO - PROCESS | 1663 | RSS: Main: 271859712 04:55:41 INFO - PROCESS | 1663 | 04:58:43 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 04:58:43 INFO - PROCESS | 1663 | RSS: Main: 273678336 04:58:43 INFO - PROCESS | 1663 | 05:01:45 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 05:01:46 INFO - PROCESS | 1663 | RSS: Main: 275222528 05:01:46 INFO - PROCESS | 1663 | 05:04:48 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 05:04:49 INFO - PROCESS | 1663 | RSS: Main: 278110208 05:04:49 INFO - PROCESS | 1663 | 05:07:52 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 05:07:52 INFO - PROCESS | 1663 | RSS: Main: 279527424 05:07:52 INFO - PROCESS | 1663 | 05:07:52 INFO - PROCESS | 1663 | __start_tp_report 05:07:52 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 05:07:52 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 05:07:52 INFO - PROCESS | 1663 | |i|pagename|runs| 05:07:52 INFO - PROCESS | 1663 | |0;dojo.html;2793.511135551278;3527.082958430808;3553.4314509678456;3567.2330448953485;3590.982045089774;5797.681127537984;5806.9709651451785;5847.26909136358;5849.7367618457165;5871.207387002753;5763.250777398932;5913.615614985034;5994.0709190075495;5996.6402015879075;6023.728932198051;5236.371635403747;5303.628745987763;5326.6537675051195;5345.545628621552;5381.19282107685;5279.128943724281;5413.891722165555;5421.972890135564;5481.945180548203;5489.176623506466;5242.449542797989;5244.423113457517;5295.4704529547125;5325.653832500894;5326.520613144816;5289.814856480005;5295.602829787781;5328.893422131536;5348.51863332298;5350.8127215547265;5193.948060519422;5195.19474481456;5233.031889100528;5260.710660913648;5297.364316282033;4563.840265590688;5706.77172913083;5761.596688231825;5772.249607550992;5817.476427121579;5464.23500709901;5844.357120716718;6265.4361107500545;6266.780662676784;6280.717367718464;6188;6298.716557754912;6305.243370795534;6308.211473565804;6311.715972781236;5898.351181370046;5987.820365389045;5988.221531200916;5990.371011043778;6005.069214271839;6145.525073982301;6377.426031657081;6394.26465956421;6456.0961465394885;6478.578892372075;5259.342582177228;5748.741306641211;6133.028644417026;6159.6612186330185;6303.36966303366;5430.402655707869;6176.826402983419;6257.998720204745;6314.0528920661345;6318.873622527523;6140.381550880894;6200.162977998003;6264.624122552661;6287.402696743802;6291.1192433059405;5762.567807414461;6164.321924588292;6246.750129994749;6269.0909818076125;6285.748570057147;5012.147934851084;5048.495150484996;5065.214891691794;5071.594272458195;5094.821681241213;4475.440569928759;4493.910121797611;4504.797284122223;4514.277715565494;4540.455145382575;4748.3114948332295;5929.407059294036;5947.6431414115295;5957.523398128226;5976.61152025117;6278.811635650938;6308.747650093945;6331.335209802996;6351.523635727339;6357.173567436296;5897.233359663216;5929.673867937305;5978.880422391528;6017.819465415957;6022.337542870281;5395.136778115561;5494.09347457665;5538.833834984957;5552.444755524496;5556.91664625031;6585.80242592723;6595.142631457879;6617.139771829824;6625.668716564152;6635.336466353519;6314.968425157845;6347.873042539216;6352.555321127476;6354;6367.586106903036;6345.555811093179;6491.48068154537;6575.178102737158;6588;6615.768448104293;6261.436470717657;6264.029075493306;6288.559800814082;6292.653904035322;6296.040860795419;6323.873522529431;6331.3352098029045;6331.430171284606;6334.90497642545;6346.619202847844;6018;6141.263048434216;6143.293521244936;6197.163382943245;6244.687765611792;6156.661383623944;6262.1546091278915;6271.560990730605;6289.213848268966;6291.905621415774;6020.8795824084145;6078.300995385587;6129.816105516842;6166.136740856194;6200.503959683305;5800.8259752207505;6107.552954153702;6179.3820617937845;6258.468030217483;6271.749130034747;6122.7244773984385;6143.815685529441;6144.723487442986;6152;6160.383961603983 05:07:52 INFO - PROCESS | 1663 | |1;ext.html;515.1551455612794;523.7198099017024;532.2362409941734;537.4759609380854;590.1089355073842;36494;37431.4385284221;37435.81282093589;37452;37516;38115.80942095292;38279.61720382802;38322.616773832255;38819.61180388202;38840.611593884125;8077.798055048612;9314.39456435333;9474.099960503741;9523.33346662932;9580.952095239549;9385.577649005778;9514.714558563253;9524;9630.75923101921;9631.951840240788;9578.377405468622;9634.47010414427;9657.179139773129;9685.370450920667;9706.174975127087;7558.546487210785;9561.569729362196;9644.903550964471;9667.5649595768;9831.459269740162;8123.878141827878;9495.810083798286;9638.662646807328;9654.420734755937;9700.27247956399;37063.629363706284;37131.814340928126;37168.44247336292;37291.813540932395;37375.25249495022;2669.746374094458;3079.7690173237097;3089.521124225726;3189.059227527885;3446.1040129566495;26637.467250654878;26948.922043118448;27052.917883284645;27068.59397109045;27464.03875864355;2747.972520274812;2750.092469485065;2766.8478233697138;2775;2861.3848022675224;411077;413856;414125;416198;419370;34398;55128.448715513136;55280.44719552834;55348.72325638426;55389;33824.830875845466;34300.656993429744;34412.139696507285;34476.310473790385;34493.31013379718;13044.869551304366;14805.62985925339;15157.924210378878;15159.09045457276;15325.386984520717;12549.121561490609;12575.93712031434;12921.353932303347;12926.806097908539;13081.672958176121;6878.449724022066;8009.759707208793;8090.393220508367;8105.31104856082;8160.795980100545;3332.3002169544666;3385.4583266677214;3394.0496660934978;3396.524486571882;3404.3701915145284;949.4730424614405;967.5452537307455;1087.152021423291;1100.4758409602757;1144.3064264888012;27663.58504622432;29083;29257.707422925498;29670.258243543652;29743.553846692317;1061.8301071828625;1138.4649214869164;1184.3308530680138;1203.2359451748202;1211.9333436661068;14809.925950370394;15024.399024038916;15028.924855375653;15302.005369650937;15416.845831541765;283421;287269;287376;288011;288378;56501.43498565044;58187.418125819044;58670.41329586649;58726.412735872094;58750.11874821881;1426.7788492783636;1459.153690859299;1483.3176738700104;1490.0016988617651;1498.707751988352;28518.57222141711;28637;29348.8639488093;29417.85291073531;29442.558361625022;55417.722911385186;55624.44375556193;56337.43662563486;56841;56997.430025699214;477942;487797;493149;493451;494141;39014.6098539011;39959.801205963864;40081.599184008955;40499.797501012305;40584.188316234104;43622.78188609164;43666.126677466906;43687;45705;46111;32177.678223218405;36446.08884777796;37632.43551346787;37877.43183852299;38535.80732096321;36052;36857.26285474329;37663;37931.81034094812;37993;36213.91358259256;36612.81693591515;36631.26737465183;37994.43008354821;38574;27567;29057.692403841455;30157.246068848453;31208.843955780078;31295.530567041056;23122.190723324602;23263.06947722072;23328.76671233266;23525.52948941046;23574.88212558926 05:07:52 INFO - PROCESS | 1663 | |2;jquery.html;391.95100612423465;392.57013570140685;397.39992611157146;398.1261131815664;402.68389314388213;19486.90256548717;20112.59774804503;20145.697814532774;20331.898340508276;21199.788002119974;20126.798732012674;20435.693464598044;20543.870087145202;20602;20637.484062898435;5702.372738998707;6063.787767428141;6161.199044124258;6177.907331390033;6196.008638617799;5818.650880947135;5946;6097.725602347905;6101.389861013885;6121.387861213888;6030.095485677161;6031.457168854802;6063.302720187169;6064.666443345614;6082.81751547452;5944.375840536768;5987.1019347097845;6046.365131661179;6054.636721796706;6123.846903827395;5278.973605131988;5307.150855863043;5309.62832601718;5324.0150572144275;5328.893422131575;20965.790342096538;23371;24954;25065.49869002628;25847.224583262534;2248.7413947396094;2293.827962902789;2367.6685264063044;2417.286900364397;2498.600223964157;14316.284185790773;14810.777838332433;14926;15148;15220.619484512865;1446.313001324373;1747.6329970706167;1753.6229710612279;1775.3519965212604;1816.727490876366;109095;110804.89195108027;111394.44302778515;112801;113592.4320378401;8489.74530764078;10339.172866170838;10342.482875856178;10416.687499375032;10428.426436546062;10499.737506562244;12306;12332.075094367958;12502.437390317458;12522.93555047831;8135.633896474673;8136.959315203386;8164.346852251806;8261.669533218605;8319.708810191736;7714.842773583895;8269.379796515286;8571.442856214451;8582.65669373218;8607.741767746978;3953.5964732520115;4027.9194416111513;4033.193361327746;4060.842659841931;4080.9795951020055;1942.8445724342018;1977.8516611254213;1991.442396129086;1997.3608445297366;2043.9693404598943;756.7237958145238;878.2359347367831;950.9049095090573;968.5157421289356;992.3153024413132;19990.800091998895;20940.895295523424;21041.263555775473;21108.683369749466;21261.893690531448;950.6767698982379;962.3888830592624;999.1207737191226;1043.8016776812533;1096.1723898456614;9095.81808363829;10359.896401036043;11027.252627133414;11080.722981925517;11099.778004439866;596162.0191899013;606006;614375;615884.9205754033;616673.916630414;121102.394488027;123765.38117309356;127709;127758.36120819337;139896.6010339904;879.2833840420037;982.9410235385757;1006.3861044762689;1023.8668973033607;1065.1159537583942;18006.009669468305;18181.272749089887;18375.54061148482;18579.721304180177;18595.535111622317;36659;51206.231906522175;52185.7390713044;52478.21282680839;54791.45208547863;61809.454763629474;71510;71545.92681109892;72517.27482725105;73879.89180162194;7009.859802804018;7728.690852365842;8016.7194148204535;8159.71440999586;8216.055153657167;11680.70798230051;12483.063770217452;12565.93717031409;12764.04269679759;12849.807252891027;10533.26267161322;10935.781284374427;11096.44517774092;11193.048590869867;11212.831807523056;8193.426460147972;10689.144868410664;10862.511186996446;11000.394978276272;11092.667219983414;10466.68599942003;10897.073748731447;11104.777904442028;11492.195546311677;11546.307221566396;7025.789226323014;7397.334239918433;7468.850622987618;7603.467757256939;8504.53225072627;9922.900770992197;11032.779344413228;11220.270682405617;11225.943870280596;11472.082233421139 05:07:52 INFO - PROCESS | 1663 | |3;mootools.html;1505.3903169216467;1602.1108284901882;1850.9259629614808;1966.7541557305337;1975.3975037613532;7364.889526657104;8047.597620119;8703.738887833368;8961.91038089618;8969;8717.433366831152;8776.824463510726;8833.646654133843;8874.689385871496;8897.199252067312;2396.916107936227;2433.5376278507115;2443.389152711822;2444.303373538542;2446.131623273739;2419.7338292787867;2428.854268743881;2453.9263822085277;2455.607102863542;2459.991403524555;2422.903083876643;2433.6714543536573;2437.1104546840365;2449.1428000199967;2472.418981539337;2432.4770174412497;2433.914812981546;2436.537057958982;2443.731189569155;2444.10790061628;2018.3440381875948;2403.579373609629;2428.963565546518;2431.8813345860926;2433.6957880264968;8943.5975381108;8970.192682658528;9015.639374425014;9033.54832258391;9036.141566551167;5310.575153987672;5451.04606693827;5465.180222966547;5495.670259784386;5544;3601.099725068733;3625.1843335249623;3635.054885729703;3658.2317713279976;3664.468652045465;1021.6730646193221;1036.528379587286;1038.5170895533613;1047.643801107627;1082.2910993299402;11803.11476639247;11839.408029598486;11905;11914.225575337661;11921.165518413714;4800.831970881072;4803.855884323476;4843.2735089736825;4933.802647894116;4963.6277279204205;10041.899581004096;10094.14199793026;10105.646302379382;10119.544620492095;10127.696169114939;5472.781088756406;5495.203195536625;5515.448455154533;5519.144532597454;5519.39286678465;4472.217361961643;4691.859244222679;4843.346148269939;4844.4186697596515;5015.247712843103;4056.8288485606963;4069.491313585802;4074.022234663719;4124.443200167939;4127.6904232182105;1154.7575009248153;1366.4192718094969;1371.5062577472102;1373.169232614274;1378.6691194113341;737.9262073792578;759.3469616130124;791.8653828849109;934.3552948465673;958.9808203835884;5535.557155427637;5672.716364181857;5716.399778023331;5976.967830937502;6041.395860413923;987.2151639446657;1009.5204777730667;1021.8416145497459;1024.4877561219391;1058.2063452410691;3770.7360484765804;3917.353636649978;3956.347202711578;3972;4013.1572369802084;10312.690619281433;10804.1896857736;10884.782304353868;10898.673039808818;10946.288491248044;10575.15398768112;10741.40922249268;10824.837627435594;10859.936612043686;10886.23796334249;8172.060213075572;8330.583470826434;8364.790880227922;8376.832463350698;8419.36854735885;5553.972230138985;5648.915266271091;5676.290463692038;5742.971285143548;5752.539796816161;9936.75158121024;10017;10068.899311006797;10100.343477673927;10141.79716405653;9776.608935642778;9953.701388958343;10177.796444071226;10339.172866170538;10350.22373322019;5747.396523365153;7317.609654165692;7497.175310715707;7559.3196612305155;7570.16728159913;8397.412181147261;8414.368922330726;8444.986601607847;8467.407281490237;8477.194666506672;7291.927080729125;7386.593737344498;7549.584772837547;7611.809704757426;7618.047744031996;7169.677364518505;7179.641017948978;7290.9635451822405;7384.409247260099;7460;7001.299870013162;7090.503664743418;7092.503524753218;7103.82240443993;7105.644717764195;3943.467631869661;4116.217918595457;4847.927281090716;4907.214845624683;5087.949120508748;4967.05625931072;4968.527989841104;4979.975100124476;5012.172991456368;5039.017391608601 05:07:52 INFO - PROCESS | 1663 | |4;prototype.html;313.43112251263955;316.9508726147447;324.55860030358707;330.16962339715604;332.11491375484337;4616.515265897083;5489.505944464997;6103.145559621651;6152.630842149474;6165.290991535969;4944.87637809054;5956;6044.879102417949;6067.421345927985;6068.544859135572;3908.433277174808;4146.771927543984;4481.91036179277;4526.8868278292975;4555.271156614942;3652.9452058219017;3930.6658933990643;4455.732656040632;4505.8422955196575;4522.6155776759015;4337.587929146726;4414.646828253732;4414.646828253732;4420.248557745191;4432.268675668511;4416.381706561084;4425.3140763181755;4428.3800267962515;4429;4468.218061839198;2993.7006299369887;3754.1365485938113;3776.470529435583;3815.1606646537716;3821.9426708599394;5289.709066001368;5654.660720356792;5665.2351932489;5670.1571989103295;5708.546329470693;4251.893702657459;4399.692021558492;4865.367502224688;4932.977479233497;5096.362954630671;5453.863653408657;6318;6355.682215889234;6382.457491113261;6440.4847612190915;3688.428293614494;4069.8604390770633;4085.59144085592;4240.1307731914885;4244.257254980397;6198.318184999646;6776.7628133015105;6805.965970170167;6812.897806532906;6828.624425656587;3613.367660659374;4008.599140086026;4220.388043733641;4306.82772689089;4344.913101737948;3640.38003088623;4300.677449191323;4308.4399028126145;4336.956630433719;4366.8034938427845;4453.819737769493;4717.905641887143;4725.881852953704;5107.770150343244;5119.513646203604;5354.464553544614;5844.649321040752;6239.376062393815;6462.450691691259;6476;1248.925064496115;1480.5558332500207;1494.3275526013338;1496.5585152380074;1501.7672260799593;949.5110018340561;965.724768441005;977.731123940922;983.6508039645822;990.5294984882123;5364.892702145935;5847.298324201123;5902.055671092605;5946;5955.374685657944;6557.606543607399;6582.354411397151;6605.207375115017;6608.735650574019;6623.172103487064;5613.522850557748;5688.591341710207;5854.882902341929;5871.412858714094;5882.794102206402;3434.587849458081;3465.6880880720855;3467.358538670354;3490.755647104729;3495.3358861816173;6696.665166741741;6769.458443324522;6868.793936181923;6878.828029299308;6893.4140598048725;6188.793185328907;6428.357164283628;6774.45804335652;6797.218319893177;6823.624700641512;5821.53427725781;6240.781572645026;6342.255879633138;6794.490413219029;7133.429325654039;6712.899306510309;6728.64497425897;6734.831629209309;6784;6784.864302714017;5746.913796292975;5892.733062391607;6649.634270114997;6657.833554161185;6676.899846502403;5268.025415298182;6613.371729685672;6619.66901654925;6623.602583844985;6879.385833383282;3851.7496362736333;3942.5860284669698;4260.169266992907;4285.335772955197;4294.334378171388;6036.456718895321;6550.115734375799;6559.114519539801;6562.80311590653;6606.834829129118;4994.875128121826;5852;6289.056641503811;6396.680165991775;6411.51913606472;5130.51260130287;5998.730057147526;6166.753329866755;6469.385408386196;6512.078936713776;5117.334746482933;6070.696465176812;6077.787277445268;6372.458341041063;6384.201974753156;6410.903836442364;6482.35176482348;6486.6648006716605;6493.448056915215;6496.480281577368;5413.7834486620095;6226.50187984951;6361.586496877688;6370.872582548416;6384.106225128393 05:07:52 INFO - PROCESS | 1663 | |5;yui.html;673.4343151752529;677.3091446724342;686.3719696477723;750.5271678842329;754.8980887580175;14953.551393458187;15071.773923391143;15099.849001509981;16780.58048548787;17499.475015749547;16139.838601613981;16501.17494125292;16674.66650666986;16702.83297167028;16739.581510462245;722.1009842745789;915.3318077803208;923.0169869089432;924.2328867040374;926.0322962504199;875.2166810704416;883.995580022099;904.6336233825311;917.1882883647953;917.9357444978854;901.6528636474939;907.6006557114881;909.1090731083542;913.8949020862586;919.4345477531303;869.2784988459595;895.9641614335484;912.4251721415532;914.8444764390036;919.1360121485816;644.8323435906651;661.9205695316545;664.6012392564422;670.4669787518934;674.5817593092314;13538.932305338509;13574.796378054338;13663.111897726616;13663.248521331323;13670.863291367157;12470.314132722697;12478.50085996559;12593.433295501725;12671.303078330688;12810.679733006657;9967.40195588267;9976.551055202533;10186.796264074677;10285.331453455576;10746.623868164577;3531.715504580739;3584.6773790358993;3629.94555081674;3717.8141092945516;3793.336166170937;62982;80085.59957200235;81569.59215203945;83162;85450.5727471365;9561.61753529851;9616.807663846825;9660.855087173699;9744.220462362993;9973.950130249303;20895.8955205223;21670.24154154597;21801.90990450471;21838.781612183673;21881.234156804436;6034.728437220336;7541.7360392386;7737.690492380354;7775.805604859811;7837.686492540234;4059.5331536873045;4158.480189976253;4248.681348898845;4250.192463431938;4306.80619372129;3557.3774589446743;3660.213054193361;3661.9267614648093;3663.065918190844;3664.8272552783387;651.8956966885371;653.7777155767062;657.2901266632026;661.3882159002903;662.9635370054693;549.0720682047328;571.5397160255504;580.364019418336;615.5414216408805;616.5191150902303;7651.196624354474;7760.146383897767;8002.639881205359;8021.598920053974;8072.233137852012;585.9941400586025;589.3046205477577;606.6875559087074;623.2396476298908;631.1952260867431;2893.4068515954177;3536.876209332661;3621.6559426854406;3807.9048023799073;3939.9015024624614;72475;88999.55500222457;91918.54040729754;92764.53617731867;94559;76147.61926190334;76842.23157768462;77366;78659;79414;67973;68469.65765171242;69036.30963690298;69930.9510357345;70292.94560581596;7446.553206807609;7625.466217364731;7667;7703.730369437043;7811.89504720495;32878.34243315073;42570.78714606531;42751.3587296197;43860.561394385644;44675;59170.40829591649;61727;64788.67605661941;64804;65062.34937650563;9711.562979666074;9714.951425242829;9784.951075244579;10000.449975251431;10428.530716117944;10860.076893464144;11183.496742646763;11195.552177912792;11238.887611124112;11272.774544509228;10131.7467063324;10142.746431339276;10226.130778883879;10239.385636861813;10797.838032429678;10261.58953641846;10268.281220314806;10291.43397113136;10320.896791031993;10646.095081918123;9950.601975920883;10037.456899759556;10084;10377.533011014673;10466.63366782159;8633.30933525329;8664.436811607226;8832.735017949472;9095.363324567217;9151.588178532114;8093.5953202340825;8665.17680820321;8750.212480876751;8872.556372181236;8973.910260897308 05:07:52 INFO - PROCESS | 1663 | __end_tp_report 05:07:52 INFO - PROCESS | 1663 | __start_cc_report 05:07:52 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,374 05:07:52 INFO - PROCESS | 1663 | __end_cc_report 05:07:52 INFO - PROCESS | 1663 | __startTimestamp1460722072358__endTimestamp 05:07:52 INFO - PROCESS | 1663 | 05:07:52 INFO - PROCESS | 1663 | ------- Summary: start ------- 05:07:52 INFO - PROCESS | 1663 | Number of tests: 6 05:07:52 INFO - PROCESS | 1663 | 05:07:52 INFO - PROCESS | 1663 | [#0] dojo.html Cycles:165 Average:5827.42 Median:6022.34 stddev:662.10 (11.0%) stddev-sans-first:619.88 05:07:52 INFO - PROCESS | 1663 | Values: 2793.5 3527.1 3553.4 3567.2 3591.0 5797.7 5807.0 5847.3 5849.7 5871.2 5763.3 5913.6 5994.1 5996.6 6023.7 5236.4 5303.6 5326.7 5345.5 5381.2 5279.1 5413.9 5422.0 5481.9 5489.2 5242.4 5244.4 5295.5 5325.7 5326.5 5289.8 5295.6 5328.9 5348.5 5350.8 5193.9 5195.2 5233.0 5260.7 5297.4 4563.8 5706.8 5761.6 5772.2 5817.5 5464.2 5844.4 6265.4 6266.8 6280.7 6188.0 6298.7 6305.2 6308.2 6311.7 5898.4 5987.8 5988.2 5990.4 6005.1 6145.5 6377.4 6394.3 6456.1 6478.6 5259.3 5748.7 6133.0 6159.7 6303.4 5430.4 6176.8 6258.0 6314.1 6318.9 6140.4 6200.2 6264.6 6287.4 6291.1 5762.6 6164.3 6246.8 6269.1 6285.7 5012.1 5048.5 5065.2 5071.6 5094.8 4475.4 4493.9 4504.8 4514.3 4540.5 4748.3 5929.4 5947.6 5957.5 5976.6 6278.8 6308.7 6331.3 6351.5 6357.2 5897.2 5929.7 5978.9 6017.8 6022.3 5395.1 5494.1 5538.8 5552.4 5556.9 6585.8 6595.1 6617.1 6625.7 6635.3 6315.0 6347.9 6352.6 6354.0 6367.6 6345.6 6491.5 6575.2 6588.0 6615.8 6261.4 6264.0 6288.6 6292.7 6296.0 6323.9 6331.3 6331.4 6334.9 6346.6 6018.0 6141.3 6143.3 6197.2 6244.7 6156.7 6262.2 6271.6 6289.2 6291.9 6020.9 6078.3 6129.8 6166.1 6200.5 5800.8 6107.6 6179.4 6258.5 6271.7 6122.7 6143.8 6144.7 6152.0 6160.4 05:07:52 INFO - PROCESS | 1663 | 05:07:52 INFO - PROCESS | 1663 | [#1] ext.html Cycles:180 Average:54134.17 Median:37333.53 stddev:107771.44 (288.7%) stddev-sans-first:107998.58 05:07:52 INFO - PROCESS | 1663 | Values: 515.2 523.7 532.2 537.5 590.1 36494.0 37431.4 37435.8 37452.0 37516.0 38115.8 38279.6 38322.6 38819.6 38840.6 8077.8 9314.4 9474.1 9523.3 9581.0 9385.6 9514.7 9524.0 9630.8 9632.0 9578.4 9634.5 9657.2 9685.4 9706.2 7558.5 9561.6 9644.9 9667.6 9831.5 8123.9 9495.8 9638.7 9654.4 9700.3 37063.6 37131.8 37168.4 37291.8 37375.3 2669.7 3079.8 3089.5 3189.1 3446.1 26637.5 26948.9 27052.9 27068.6 27464.0 2748.0 2750.1 2766.8 2775.0 2861.4 411077.0 413856.0 414125.0 416198.0 419370.0 34398.0 55128.4 55280.4 55348.7 55389.0 33824.8 34300.7 34412.1 34476.3 34493.3 13044.9 14805.6 15157.9 15159.1 15325.4 12549.1 12575.9 12921.4 12926.8 13081.7 6878.4 8009.8 8090.4 8105.3 8160.8 3332.3 3385.5 3394.0 3396.5 3404.4 949.5 967.5 1087.2 1100.5 1144.3 27663.6 29083.0 29257.7 29670.3 29743.6 1061.8 1138.5 1184.3 1203.2 1211.9 14809.9 15024.4 15028.9 15302.0 15416.8 283421.0 287269.0 287376.0 288011.0 288378.0 56501.4 58187.4 58670.4 58726.4 58750.1 1426.8 1459.2 1483.3 1490.0 1498.7 28518.6 28637.0 29348.9 29417.9 29442.6 55417.7 55624.4 56337.4 56841.0 56997.4 477942.0 487797.0 493149.0 493451.0 494141.0 39014.6 39959.8 40081.6 40499.8 40584.2 43622.8 43666.1 43687.0 45705.0 46111.0 32177.7 36446.1 37632.4 37877.4 38535.8 36052.0 36857.3 37663.0 37931.8 37993.0 36213.9 36612.8 36631.3 37994.4 38574.0 27567.0 29057.7 30157.2 31208.8 31295.5 23122.2 23263.1 23328.8 23525.5 23574.9 05:07:52 INFO - PROCESS | 1663 | 05:07:52 INFO - PROCESS | 1663 | [#2] jquery.html Cycles:180 Average:34758.81 Median:2392.48 stddev:101473.13 (4241.3%) stddev-sans-first:101724.97 05:07:52 INFO - PROCESS | 1663 | Values: 392.0 392.6 397.4 398.1 402.7 19486.9 20112.6 20145.7 20331.9 21199.8 20126.8 20435.7 20543.9 20602.0 20637.5 5702.4 6063.8 6161.2 6177.9 6196.0 5818.7 5946.0 6097.7 6101.4 6121.4 6030.1 6031.5 6063.3 6064.7 6082.8 5944.4 5987.1 6046.4 6054.6 6123.8 5279.0 5307.2 5309.6 5324.0 5328.9 20965.8 23371.0 24954.0 25065.5 25847.2 2248.7 2293.8 2367.7 2417.3 2498.6 14316.3 14810.8 14926.0 15148.0 15220.6 1446.3 1747.6 1753.6 1775.4 1816.7 109095.0 110804.9 111394.4 112801.0 113592.4 8489.7 10339.2 10342.5 10416.7 10428.4 10499.7 12306.0 12332.1 12502.4 12522.9 8135.6 8137.0 8164.3 8261.7 8319.7 7714.8 8269.4 8571.4 8582.7 8607.7 3953.6 4027.9 4033.2 4060.8 4081.0 1942.8 1977.9 1991.4 1997.4 2044.0 756.7 878.2 950.9 968.5 992.3 19990.8 20940.9 21041.3 21108.7 21261.9 950.7 962.4 999.1 1043.8 1096.2 9095.8 10359.9 11027.3 11080.7 11099.8 596162.0 606006.0 614375.0 615884.9 616673.9 121102.4 123765.4 127709.0 127758.4 139896.6 879.3 982.9 1006.4 1023.9 1065.1 18006.0 18181.3 18375.5 18579.7 18595.5 36659.0 51206.2 52185.7 52478.2 54791.5 61809.5 71510.0 71545.9 72517.3 73879.9 7009.9 7728.7 8016.7 8159.7 8216.1 11680.7 12483.1 12565.9 12764.0 12849.8 10533.3 10935.8 11096.4 11193.0 11212.8 8193.4 10689.1 10862.5 11000.4 11092.7 10466.7 10897.1 11104.8 11492.2 11546.3 7025.8 7397.3 7468.9 7603.5 8504.5 9922.9 11032.8 11220.3 11225.9 11472.1 05:07:52 INFO - PROCESS | 1663 | 05:07:52 INFO - PROCESS | 1663 | [#3] mootools.html Cycles:180 Average:5752.90 Median:4802.34 stddev:3214.00 (66.9%) stddev-sans-first:3207.17 05:07:52 INFO - PROCESS | 1663 | Values: 1505.4 1602.1 1850.9 1966.8 1975.4 7364.9 8047.6 8703.7 8961.9 8969.0 8717.4 8776.8 8833.6 8874.7 8897.2 2396.9 2433.5 2443.4 2444.3 2446.1 2419.7 2428.9 2453.9 2455.6 2460.0 2422.9 2433.7 2437.1 2449.1 2472.4 2432.5 2433.9 2436.5 2443.7 2444.1 2018.3 2403.6 2429.0 2431.9 2433.7 8943.6 8970.2 9015.6 9033.5 9036.1 5310.6 5451.0 5465.2 5495.7 5544.0 3601.1 3625.2 3635.1 3658.2 3664.5 1021.7 1036.5 1038.5 1047.6 1082.3 11803.1 11839.4 11905.0 11914.2 11921.2 4800.8 4803.9 4843.3 4933.8 4963.6 10041.9 10094.1 10105.6 10119.5 10127.7 5472.8 5495.2 5515.4 5519.1 5519.4 4472.2 4691.9 4843.3 4844.4 5015.2 4056.8 4069.5 4074.0 4124.4 4127.7 1154.8 1366.4 1371.5 1373.2 1378.7 737.9 759.3 791.9 934.4 959.0 5535.6 5672.7 5716.4 5977.0 6041.4 987.2 1009.5 1021.8 1024.5 1058.2 3770.7 3917.4 3956.3 3972.0 4013.2 10312.7 10804.2 10884.8 10898.7 10946.3 10575.2 10741.4 10824.8 10859.9 10886.2 8172.1 8330.6 8364.8 8376.8 8419.4 5554.0 5648.9 5676.3 5743.0 5752.5 9936.8 10017.0 10068.9 10100.3 10141.8 9776.6 9953.7 10177.8 10339.2 10350.2 5747.4 7317.6 7497.2 7559.3 7570.2 8397.4 8414.4 8445.0 8467.4 8477.2 7291.9 7386.6 7549.6 7611.8 7618.0 7169.7 7179.6 7291.0 7384.4 7460.0 7001.3 7090.5 7092.5 7103.8 7105.6 3943.5 4116.2 4847.9 4907.2 5087.9 4967.1 4968.5 4980.0 5012.2 5039.0 05:07:52 INFO - PROCESS | 1663 | 05:07:52 INFO - PROCESS | 1663 | [#4] prototype.html Cycles:180 Average:5110.77 Median:5845.97 stddev:1647.66 (28.2%) stddev-sans-first:1612.45 05:07:52 INFO - PROCESS | 1663 | Values: 313.4 317.0 324.6 330.2 332.1 4616.5 5489.5 6103.1 6152.6 6165.3 4944.9 5956.0 6044.9 6067.4 6068.5 3908.4 4146.8 4481.9 4526.9 4555.3 3652.9 3930.7 4455.7 4505.8 4522.6 4337.6 4414.6 4414.6 4420.2 4432.3 4416.4 4425.3 4428.4 4429.0 4468.2 2993.7 3754.1 3776.5 3815.2 3821.9 5289.7 5654.7 5665.2 5670.2 5708.5 4251.9 4399.7 4865.4 4933.0 5096.4 5453.9 6318.0 6355.7 6382.5 6440.5 3688.4 4069.9 4085.6 4240.1 4244.3 6198.3 6776.8 6806.0 6812.9 6828.6 3613.4 4008.6 4220.4 4306.8 4344.9 3640.4 4300.7 4308.4 4337.0 4366.8 4453.8 4717.9 4725.9 5107.8 5119.5 5354.5 5844.6 6239.4 6462.5 6476.0 1248.9 1480.6 1494.3 1496.6 1501.8 949.5 965.7 977.7 983.7 990.5 5364.9 5847.3 5902.1 5946.0 5955.4 6557.6 6582.4 6605.2 6608.7 6623.2 5613.5 5688.6 5854.9 5871.4 5882.8 3434.6 3465.7 3467.4 3490.8 3495.3 6696.7 6769.5 6868.8 6878.8 6893.4 6188.8 6428.4 6774.5 6797.2 6823.6 5821.5 6240.8 6342.3 6794.5 7133.4 6712.9 6728.6 6734.8 6784.0 6784.9 5746.9 5892.7 6649.6 6657.8 6676.9 5268.0 6613.4 6619.7 6623.6 6879.4 3851.7 3942.6 4260.2 4285.3 4294.3 6036.5 6550.1 6559.1 6562.8 6606.8 4994.9 5852.0 6289.1 6396.7 6411.5 5130.5 5998.7 6166.8 6469.4 6512.1 5117.3 6070.7 6077.8 6372.5 6384.2 6410.9 6482.4 6486.7 6493.4 6496.5 5413.8 6226.5 6361.6 6370.9 6384.1 05:07:52 INFO - PROCESS | 1663 | 05:07:52 INFO - PROCESS | 1663 | [#5] yui.html Cycles:180 Average:17571.82 Median:64933.17 stddev:24774.90 (38.2%) stddev-sans-first:24811.90 05:07:52 INFO - PROCESS | 1663 | Values: 673.4 677.3 686.4 750.5 754.9 14953.6 15071.8 15099.8 16780.6 17499.5 16139.8 16501.2 16674.7 16702.8 16739.6 722.1 915.3 923.0 924.2 926.0 875.2 884.0 904.6 917.2 917.9 901.7 907.6 909.1 913.9 919.4 869.3 896.0 912.4 914.8 919.1 644.8 661.9 664.6 670.5 674.6 13538.9 13574.8 13663.1 13663.2 13670.9 12470.3 12478.5 12593.4 12671.3 12810.7 9967.4 9976.6 10186.8 10285.3 10746.6 3531.7 3584.7 3629.9 3717.8 3793.3 62982.0 80085.6 81569.6 83162.0 85450.6 9561.6 9616.8 9660.9 9744.2 9974.0 20895.9 21670.2 21801.9 21838.8 21881.2 6034.7 7541.7 7737.7 7775.8 7837.7 4059.5 4158.5 4248.7 4250.2 4306.8 3557.4 3660.2 3661.9 3663.1 3664.8 651.9 653.8 657.3 661.4 663.0 549.1 571.5 580.4 615.5 616.5 7651.2 7760.1 8002.6 8021.6 8072.2 586.0 589.3 606.7 623.2 631.2 2893.4 3536.9 3621.7 3807.9 3939.9 72475.0 88999.6 91918.5 92764.5 94559.0 76147.6 76842.2 77366.0 78659.0 79414.0 67973.0 68469.7 69036.3 69931.0 70292.9 7446.6 7625.5 7667.0 7703.7 7811.9 32878.3 42570.8 42751.4 43860.6 44675.0 59170.4 61727.0 64788.7 64804.0 65062.3 9711.6 9715.0 9785.0 10000.4 10428.5 10860.1 11183.5 11195.6 11238.9 11272.8 10131.7 10142.7 10226.1 10239.4 10797.8 10261.6 10268.3 10291.4 10320.9 10646.1 9950.6 10037.5 10084.0 10377.5 10466.6 8633.3 8664.4 8832.7 9095.4 9151.6 8093.6 8665.2 8750.2 8872.6 8973.9 05:07:52 INFO - PROCESS | 1663 | -------- Summary: end -------- 05:07:52 INFO - PROCESS | 1663 | 05:07:52 INFO - TEST-INFO | 1663: exit 0 05:07:52 INFO - TEST-OK | dromaeo_css | took 1112186ms 05:07:52 INFO - TEST-START | kraken 05:07:52 INFO - operating with platform_type : mac_ 05:07:52 INFO - Initialising browser for kraken test... 05:07:52 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/tmpehU2no/profile http://localhost:49271/getInfo.html) 05:07:55 INFO - PROCESS | 1674 | JavaScript error: jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/omni.ja!/components/Weave.js, line 13: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIXPCComponents_Utils.import] 05:07:55 INFO - PROCESS | 1674 | __metrics Screen width/height:1600/1200 05:07:55 INFO - PROCESS | 1674 | colorDepth:24 05:07:55 INFO - PROCESS | 1674 | Browser inner width/height: 1024/689 05:07:55 INFO - PROCESS | 1674 | __metrics 05:07:55 ERROR - PROCESS | 1674 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 05:07:56 INFO - TEST-INFO | 1674: exit 0 05:07:56 INFO - Browser initialized. 05:07:56 INFO - Running cycle 1/1 for kraken test... 05:07:56 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/tmpehU2no/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:08:08 INFO - PROCESS | 1677 | RSS: Main: 247947264 05:08:08 INFO - PROCESS | 1677 | 05:08:11 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 05:08:12 INFO - PROCESS | 1677 | RSS: Main: 260198400 05:08:12 INFO - PROCESS | 1677 | 05:08:15 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 05:08:15 INFO - PROCESS | 1677 | RSS: Main: 269156352 05:08:15 INFO - PROCESS | 1677 | 05:08:19 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 05:08:19 INFO - PROCESS | 1677 | RSS: Main: 272261120 05:08:19 INFO - PROCESS | 1677 | 05:08:22 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 05:08:22 INFO - PROCESS | 1677 | RSS: Main: 273256448 05:08:22 INFO - PROCESS | 1677 | 05:08:24 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:08:25 INFO - PROCESS | 1677 | RSS: Main: 275230720 05:08:25 INFO - PROCESS | 1677 | 05:08:28 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 05:08:28 INFO - PROCESS | 1677 | RSS: Main: 274825216 05:08:28 INFO - PROCESS | 1677 | 05:08:31 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 05:08:32 INFO - PROCESS | 1677 | RSS: Main: 275091456 05:08:32 INFO - PROCESS | 1677 | 05:08:35 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 05:08:35 INFO - PROCESS | 1677 | RSS: Main: 275120128 05:08:35 INFO - PROCESS | 1677 | 05:08:37 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:08:38 INFO - PROCESS | 1677 | RSS: Main: 275099648 05:08:38 INFO - PROCESS | 1677 | 05:08:40 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:08:40 INFO - PROCESS | 1677 | RSS: Main: 275042304 05:08:40 INFO - PROCESS | 1677 | 05:08:42 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:08:43 INFO - PROCESS | 1677 | RSS: Main: 275378176 05:08:43 INFO - PROCESS | 1677 | 05:08:46 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:08:47 INFO - PROCESS | 1677 | RSS: Main: 275603456 05:08:47 INFO - PROCESS | 1677 | 05:08:50 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:08:50 INFO - PROCESS | 1677 | RSS: Main: 275505152 05:08:50 INFO - PROCESS | 1677 | 05:08:52 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 05:08:52 INFO - PROCESS | 1677 | RSS: Main: 276926464 05:08:52 INFO - PROCESS | 1677 | 05:08:52 INFO - PROCESS | 1677 | __start_tp_report 05:08:52 INFO - PROCESS | 1677 | _x_x_mozilla_page_load 05:08:52 INFO - PROCESS | 1677 | _x_x_mozilla_page_load_details 05:08:52 INFO - PROCESS | 1677 | |i|pagename|runs| 05:08:52 INFO - PROCESS | 1677 | |0;ai-astar;131;151;116;117;154;158;118;130;169;120 05:08:52 INFO - PROCESS | 1677 | |1;audio-beat-detection;175;156;150;198;149;134;139;128;188;115 05:08:52 INFO - PROCESS | 1677 | |2;audio-dft;242;256;201;194;240;202;264;277;230;205 05:08:52 INFO - PROCESS | 1677 | |3;audio-fft;66;82;74;169;46;52;90;94;153;58 05:08:52 INFO - PROCESS | 1677 | |4;audio-oscillator;83;127;179;75;83;82;123;77;66;146 05:08:52 INFO - PROCESS | 1677 | |5;imaging-gaussian-blur;92;88;90;89;91;87;92;89;91;87 05:08:52 INFO - PROCESS | 1677 | |6;imaging-darkroom;96;97;97;96;97;130;103;153;117;96 05:08:52 INFO - PROCESS | 1677 | |7;imaging-desaturate;93;102;138;90;93;91;91;95;109;91 05:08:52 INFO - PROCESS | 1677 | |8;json-parse-financial;61;60;59;59;63;63;59;76;62;59 05:08:52 INFO - PROCESS | 1677 | |9;json-stringify-tinderbox;69;96;105;103;104;73;73;67;69;69 05:08:52 INFO - PROCESS | 1677 | |10;stanford-crypto-aes;87;86;89;88;85;86;90;90;89;87 05:08:52 INFO - PROCESS | 1677 | |11;stanford-crypto-ccm;144;171;166;167;142;156;169;156;203;195 05:08:52 INFO - PROCESS | 1677 | |12;stanford-crypto-pbkdf2;172;172;173;178;214;230;230;183;168;172 05:08:52 INFO - PROCESS | 1677 | |13;stanford-crypto-sha256-iterative;68;72;69;67;76;69;76;75;72;71 05:08:52 INFO - PROCESS | 1677 | __end_tp_report 05:08:52 INFO - PROCESS | 1677 | __start_cc_report 05:08:52 INFO - PROCESS | 1677 | _x_x_mozilla_cycle_collect,974 05:08:52 INFO - PROCESS | 1677 | __end_cc_report 05:08:52 INFO - PROCESS | 1677 | __startTimestamp1460722132905__endTimestamp 05:08:52 INFO - PROCESS | 1677 | 05:08:52 INFO - PROCESS | 1677 | ------- Summary: start ------- 05:08:52 INFO - PROCESS | 1677 | Number of tests: 14 05:08:52 INFO - PROCESS | 1677 | 05:08:52 INFO - PROCESS | 1677 | [#0] ai-astar Cycles:10 Average:136.40 Median:141.00 stddev:19.78 (14.0%) stddev-sans-first:20.89 05:08:52 INFO - PROCESS | 1677 | Values: 131.0 151.0 116.0 117.0 154.0 158.0 118.0 130.0 169.0 120.0 05:08:52 INFO - PROCESS | 1677 | 05:08:52 INFO - PROCESS | 1677 | [#1] audio-beat-detection Cycles:10 Average:153.20 Median:153.00 stddev:26.65 (17.4%) stddev-sans-first:27.08 05:08:52 INFO - PROCESS | 1677 | Values: 175.0 156.0 150.0 198.0 149.0 134.0 139.0 128.0 188.0 115.0 05:08:52 INFO - PROCESS | 1677 | 05:08:52 INFO - PROCESS | 1677 | [#2] audio-dft Cycles:10 Average:231.10 Median:241.00 stddev:29.47 (12.2%) stddev-sans-first:31.00 05:08:52 INFO - PROCESS | 1677 | Values: 242.0 256.0 201.0 194.0 240.0 202.0 264.0 277.0 230.0 205.0 05:08:52 INFO - PROCESS | 1677 | 05:08:52 INFO - PROCESS | 1677 | [#3] audio-fft Cycles:10 Average:88.40 Median:70.00 stddev:41.50 (59.3%) stddev-sans-first:43.22 05:08:52 INFO - PROCESS | 1677 | Values: 66.0 82.0 74.0 169.0 46.0 52.0 90.0 94.0 153.0 58.0 05:08:52 INFO - PROCESS | 1677 | 05:08:52 INFO - PROCESS | 1677 | [#4] audio-oscillator Cycles:10 Average:104.10 Median:76.00 stddev:37.50 (49.3%) stddev-sans-first:38.99 05:08:52 INFO - PROCESS | 1677 | Values: 83.0 127.0 179.0 75.0 83.0 82.0 123.0 77.0 66.0 146.0 05:08:52 INFO - PROCESS | 1677 | 05:08:52 INFO - PROCESS | 1677 | [#5] imaging-gaussian-blur Cycles:10 Average:89.60 Median:90.50 stddev:1.90 (2.1%) stddev-sans-first:1.80 05:08:52 INFO - PROCESS | 1677 | Values: 92.0 88.0 90.0 89.0 91.0 87.0 92.0 89.0 91.0 87.0 05:08:52 INFO - PROCESS | 1677 | 05:08:52 INFO - PROCESS | 1677 | [#6] imaging-darkroom Cycles:10 Average:108.20 Median:96.00 stddev:19.46 (20.3%) stddev-sans-first:20.14 05:08:52 INFO - PROCESS | 1677 | Values: 96.0 97.0 97.0 96.0 97.0 130.0 103.0 153.0 117.0 96.0 05:08:52 INFO - PROCESS | 1677 | 05:08:52 INFO - PROCESS | 1677 | [#7] imaging-desaturate Cycles:10 Average:99.30 Median:91.00 stddev:14.87 (16.3%) stddev-sans-first:15.60 05:08:52 INFO - PROCESS | 1677 | Values: 93.0 102.0 138.0 90.0 93.0 91.0 91.0 95.0 109.0 91.0 05:08:52 INFO - PROCESS | 1677 | 05:08:52 INFO - PROCESS | 1677 | [#8] json-parse-financial Cycles:10 Average:62.10 Median:61.50 stddev:5.15 (8.4%) stddev-sans-first:5.45 05:08:52 INFO - PROCESS | 1677 | Values: 61.0 60.0 59.0 59.0 63.0 63.0 59.0 76.0 62.0 59.0 05:08:52 INFO - PROCESS | 1677 | 05:08:52 INFO - PROCESS | 1677 | [#9] json-stringify-tinderbox Cycles:10 Average:82.80 Median:69.00 stddev:16.79 (24.3%) stddev-sans-first:17.05 05:08:52 INFO - PROCESS | 1677 | Values: 69.0 96.0 105.0 103.0 104.0 73.0 73.0 67.0 69.0 69.0 05:08:52 INFO - PROCESS | 1677 | 05:08:52 INFO - PROCESS | 1677 | [#10] stanford-crypto-aes Cycles:10 Average:87.70 Median:88.50 stddev:1.77 (2.0%) stddev-sans-first:1.86 05:08:52 INFO - PROCESS | 1677 | Values: 87.0 86.0 89.0 88.0 85.0 86.0 90.0 90.0 89.0 87.0 05:08:52 INFO - PROCESS | 1677 | 05:08:52 INFO - PROCESS | 1677 | [#11] stanford-crypto-ccm Cycles:10 Average:166.90 Median:168.00 stddev:19.71 (11.7%) stddev-sans-first:19.09 05:08:52 INFO - PROCESS | 1677 | Values: 144.0 171.0 166.0 167.0 142.0 156.0 169.0 156.0 203.0 195.0 05:08:52 INFO - PROCESS | 1677 | 05:08:52 INFO - PROCESS | 1677 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.20 Median:180.50 stddev:25.18 (14.0%) stddev-sans-first:25.93 05:08:52 INFO - PROCESS | 1677 | Values: 172.0 172.0 173.0 178.0 214.0 230.0 230.0 183.0 168.0 172.0 05:08:52 INFO - PROCESS | 1677 | 05:08:52 INFO - PROCESS | 1677 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.50 Median:72.00 stddev:3.31 (4.6%) stddev-sans-first:3.26 05:08:52 INFO - PROCESS | 1677 | Values: 68.0 72.0 69.0 67.0 76.0 69.0 76.0 75.0 72.0 71.0 05:08:52 INFO - PROCESS | 1677 | -------- Summary: end -------- 05:08:52 INFO - PROCESS | 1677 | 05:08:53 INFO - TEST-INFO | 1677: exit 0 05:08:53 INFO - TEST-OK | kraken | took 60799ms 05:08:53 INFO - SUITE-END | took 1172s 05:08:54 INFO - Completed test suite (00:19:33) 05:08:54 INFO - javascript benchmark 05:08:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2793.511135551278, 3527.082958430808, 3553.4314509678456, 3567.2330448953485, 3590.982045089774, 5797.681127537984, 5806.9709651451785, 5847.26909136358, 5849.7367618457165, 5871.207387002753, 5763.250777398932, 5913.615614985034, 5994.0709190075495, 5996.6402015879075, 6023.728932198051, 5236.371635403747, 5303.628745987763, 5326.6537675051195, 5345.545628621552, 5381.19282107685, 5279.128943724281, 5413.891722165555, 5421.972890135564, 5481.945180548203, 5489.176623506466, 5242.449542797989, 5244.423113457517, 5295.4704529547125, 5325.653832500894, 5326.520613144816, 5289.814856480005, 5295.602829787781, 5328.893422131536, 5348.51863332298, 5350.8127215547265, 5193.948060519422, 5195.19474481456, 5233.031889100528, 5260.710660913648, 5297.364316282033, 4563.840265590688, 5706.77172913083, 5761.596688231825, 5772.249607550992, 5817.476427121579, 5464.23500709901, 5844.357120716718, 6265.4361107500545, 6266.780662676784, 6280.717367718464, 6188.0, 6298.716557754912, 6305.243370795534, 6308.211473565804, 6311.715972781236, 5898.351181370046, 5987.820365389045, 5988.221531200916, 5990.371011043778, 6005.069214271839, 6145.525073982301, 6377.426031657081, 6394.26465956421, 6456.0961465394885, 6478.578892372075, 5259.342582177228, 5748.741306641211, 6133.028644417026, 6159.6612186330185, 6303.36966303366, 5430.402655707869, 6176.826402983419, 6257.998720204745, 6314.0528920661345, 6318.873622527523, 6140.381550880894, 6200.162977998003, 6264.624122552661, 6287.402696743802, 6291.1192433059405, 5762.567807414461, 6164.321924588292, 6246.750129994749, 6269.0909818076125, 6285.748570057147, 5012.147934851084, 5048.495150484996, 5065.214891691794, 5071.594272458195, 5094.821681241213, 4475.440569928759, 4493.910121797611, 4504.797284122223, 4514.277715565494, 4540.455145382575, 4748.3114948332295, 5929.407059294036, 5947.6431414115295, 5957.523398128226, 5976.61152025117, 6278.811635650938, 6308.747650093945, 6331.335209802996, 6351.523635727339, 6357.173567436296, 5897.233359663216, 5929.673867937305, 5978.880422391528, 6017.819465415957, 6022.337542870281, 5395.136778115561, 5494.09347457665, 5538.833834984957, 5552.444755524496, 5556.91664625031, 6585.80242592723, 6595.142631457879, 6617.139771829824, 6625.668716564152, 6635.336466353519, 6314.968425157845, 6347.873042539216, 6352.555321127476, 6354.0, 6367.586106903036, 6345.555811093179, 6491.48068154537, 6575.178102737158, 6588.0, 6615.768448104293, 6261.436470717657, 6264.029075493306, 6288.559800814082, 6292.653904035322, 6296.040860795419, 6323.873522529431, 6331.3352098029045, 6331.430171284606, 6334.90497642545, 6346.619202847844, 6018.0, 6141.263048434216, 6143.293521244936, 6197.163382943245, 6244.687765611792, 6156.661383623944, 6262.1546091278915, 6271.560990730605, 6289.213848268966, 6291.905621415774, 6020.8795824084145, 6078.300995385587, 6129.816105516842, 6166.136740856194, 6200.503959683305, 5800.8259752207505, 6107.552954153702, 6179.3820617937845, 6258.468030217483, 6271.749130034747, 6122.7244773984385, 6143.815685529441, 6144.723487442986, 6152.0, 6160.383961603983], "value": 5786.046388693637, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [515.1551455612794, 523.7198099017024, 532.2362409941734, 537.4759609380854, 590.1089355073842, 36494.0, 37431.4385284221, 37435.81282093589, 37452.0, 37516.0, 38115.80942095292, 38279.61720382802, 38322.616773832255, 38819.61180388202, 38840.611593884125, 8077.798055048612, 9314.39456435333, 9474.099960503741, 9523.33346662932, 9580.952095239549, 9385.577649005778, 9514.714558563253, 9524.0, 9630.75923101921, 9631.951840240788, 9578.377405468622, 9634.47010414427, 9657.179139773129, 9685.370450920667, 9706.174975127087, 7558.546487210785, 9561.569729362196, 9644.903550964471, 9667.5649595768, 9831.459269740162, 8123.878141827878, 9495.810083798286, 9638.662646807328, 9654.420734755937, 9700.27247956399, 37063.629363706284, 37131.814340928126, 37168.44247336292, 37291.813540932395, 37375.25249495022, 2669.746374094458, 3079.7690173237097, 3089.521124225726, 3189.059227527885, 3446.1040129566495, 26637.467250654878, 26948.922043118448, 27052.917883284645, 27068.59397109045, 27464.03875864355, 2747.972520274812, 2750.092469485065, 2766.8478233697138, 2775.0, 2861.3848022675224, 411077.0, 413856.0, 414125.0, 416198.0, 419370.0, 34398.0, 55128.448715513136, 55280.44719552834, 55348.72325638426, 55389.0, 33824.830875845466, 34300.656993429744, 34412.139696507285, 34476.310473790385, 34493.31013379718, 13044.869551304366, 14805.62985925339, 15157.924210378878, 15159.09045457276, 15325.386984520717, 12549.121561490609, 12575.93712031434, 12921.353932303347, 12926.806097908539, 13081.672958176121, 6878.449724022066, 8009.759707208793, 8090.393220508367, 8105.31104856082, 8160.795980100545, 3332.3002169544666, 3385.4583266677214, 3394.0496660934978, 3396.524486571882, 3404.3701915145284, 949.4730424614405, 967.5452537307455, 1087.152021423291, 1100.4758409602757, 1144.3064264888012, 27663.58504622432, 29083.0, 29257.707422925498, 29670.258243543652, 29743.553846692317, 1061.8301071828625, 1138.4649214869164, 1184.3308530680138, 1203.2359451748202, 1211.9333436661068, 14809.925950370394, 15024.399024038916, 15028.924855375653, 15302.005369650937, 15416.845831541765, 283421.0, 287269.0, 287376.0, 288011.0, 288378.0, 56501.43498565044, 58187.418125819044, 58670.41329586649, 58726.412735872094, 58750.11874821881, 1426.7788492783636, 1459.153690859299, 1483.3176738700104, 1490.0016988617651, 1498.707751988352, 28518.57222141711, 28637.0, 29348.8639488093, 29417.85291073531, 29442.558361625022, 55417.722911385186, 55624.44375556193, 56337.43662563486, 56841.0, 56997.430025699214, 477942.0, 487797.0, 493149.0, 493451.0, 494141.0, 39014.6098539011, 39959.801205963864, 40081.599184008955, 40499.797501012305, 40584.188316234104, 43622.78188609164, 43666.126677466906, 43687.0, 45705.0, 46111.0, 32177.678223218405, 36446.08884777796, 37632.43551346787, 37877.43183852299, 38535.80732096321, 36052.0, 36857.26285474329, 37663.0, 37931.81034094812, 37993.0, 36213.91358259256, 36612.81693591515, 36631.26737465183, 37994.43008354821, 38574.0, 27567.0, 29057.692403841455, 30157.246068848453, 31208.843955780078, 31295.530567041056, 23122.190723324602, 23263.06947722072, 23328.76671233266, 23525.52948941046, 23574.88212558926], "value": 17867.007660458265, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [391.95100612423465, 392.57013570140685, 397.39992611157146, 398.1261131815664, 402.68389314388213, 19486.90256548717, 20112.59774804503, 20145.697814532774, 20331.898340508276, 21199.788002119974, 20126.798732012674, 20435.693464598044, 20543.870087145202, 20602.0, 20637.484062898435, 5702.372738998707, 6063.787767428141, 6161.199044124258, 6177.907331390033, 6196.008638617799, 5818.650880947135, 5946.0, 6097.725602347905, 6101.389861013885, 6121.387861213888, 6030.095485677161, 6031.457168854802, 6063.302720187169, 6064.666443345614, 6082.81751547452, 5944.375840536768, 5987.1019347097845, 6046.365131661179, 6054.636721796706, 6123.846903827395, 5278.973605131988, 5307.150855863043, 5309.62832601718, 5324.0150572144275, 5328.893422131575, 20965.790342096538, 23371.0, 24954.0, 25065.49869002628, 25847.224583262534, 2248.7413947396094, 2293.827962902789, 2367.6685264063044, 2417.286900364397, 2498.600223964157, 14316.284185790773, 14810.777838332433, 14926.0, 15148.0, 15220.619484512865, 1446.313001324373, 1747.6329970706167, 1753.6229710612279, 1775.3519965212604, 1816.727490876366, 109095.0, 110804.89195108027, 111394.44302778515, 112801.0, 113592.4320378401, 8489.74530764078, 10339.172866170838, 10342.482875856178, 10416.687499375032, 10428.426436546062, 10499.737506562244, 12306.0, 12332.075094367958, 12502.437390317458, 12522.93555047831, 8135.633896474673, 8136.959315203386, 8164.346852251806, 8261.669533218605, 8319.708810191736, 7714.842773583895, 8269.379796515286, 8571.442856214451, 8582.65669373218, 8607.741767746978, 3953.5964732520115, 4027.9194416111513, 4033.193361327746, 4060.842659841931, 4080.9795951020055, 1942.8445724342018, 1977.8516611254213, 1991.442396129086, 1997.3608445297366, 2043.9693404598943, 756.7237958145238, 878.2359347367831, 950.9049095090573, 968.5157421289356, 992.3153024413132, 19990.800091998895, 20940.895295523424, 21041.263555775473, 21108.683369749466, 21261.893690531448, 950.6767698982379, 962.3888830592624, 999.1207737191226, 1043.8016776812533, 1096.1723898456614, 9095.81808363829, 10359.896401036043, 11027.252627133414, 11080.722981925517, 11099.778004439866, 596162.0191899013, 606006.0, 614375.0, 615884.9205754033, 616673.916630414, 121102.394488027, 123765.38117309356, 127709.0, 127758.36120819337, 139896.6010339904, 879.2833840420037, 982.9410235385757, 1006.3861044762689, 1023.8668973033607, 1065.1159537583942, 18006.009669468305, 18181.272749089887, 18375.54061148482, 18579.721304180177, 18595.535111622317, 36659.0, 51206.231906522175, 52185.7390713044, 52478.21282680839, 54791.45208547863, 61809.454763629474, 71510.0, 71545.92681109892, 72517.27482725105, 73879.89180162194, 7009.859802804018, 7728.690852365842, 8016.7194148204535, 8159.71440999586, 8216.055153657167, 11680.70798230051, 12483.063770217452, 12565.93717031409, 12764.04269679759, 12849.807252891027, 10533.26267161322, 10935.781284374427, 11096.44517774092, 11193.048590869867, 11212.831807523056, 8193.426460147972, 10689.144868410664, 10862.511186996446, 11000.394978276272, 11092.667219983414, 10466.68599942003, 10897.073748731447, 11104.777904442028, 11492.195546311677, 11546.307221566396, 7025.789226323014, 7397.334239918433, 7468.850622987618, 7603.467757256939, 8504.53225072627, 9922.900770992197, 11032.779344413228, 11220.270682405617, 11225.943870280596, 11472.082233421139], "value": 9445.136171276794, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1505.3903169216467, 1602.1108284901882, 1850.9259629614808, 1966.7541557305337, 1975.3975037613532, 7364.889526657104, 8047.597620119, 8703.738887833368, 8961.91038089618, 8969.0, 8717.433366831152, 8776.824463510726, 8833.646654133843, 8874.689385871496, 8897.199252067312, 2396.916107936227, 2433.5376278507115, 2443.389152711822, 2444.303373538542, 2446.131623273739, 2419.7338292787867, 2428.854268743881, 2453.9263822085277, 2455.607102863542, 2459.991403524555, 2422.903083876643, 2433.6714543536573, 2437.1104546840365, 2449.1428000199967, 2472.418981539337, 2432.4770174412497, 2433.914812981546, 2436.537057958982, 2443.731189569155, 2444.10790061628, 2018.3440381875948, 2403.579373609629, 2428.963565546518, 2431.8813345860926, 2433.6957880264968, 8943.5975381108, 8970.192682658528, 9015.639374425014, 9033.54832258391, 9036.141566551167, 5310.575153987672, 5451.04606693827, 5465.180222966547, 5495.670259784386, 5544.0, 3601.099725068733, 3625.1843335249623, 3635.054885729703, 3658.2317713279976, 3664.468652045465, 1021.6730646193221, 1036.528379587286, 1038.5170895533613, 1047.643801107627, 1082.2910993299402, 11803.11476639247, 11839.408029598486, 11905.0, 11914.225575337661, 11921.165518413714, 4800.831970881072, 4803.855884323476, 4843.2735089736825, 4933.802647894116, 4963.6277279204205, 10041.899581004096, 10094.14199793026, 10105.646302379382, 10119.544620492095, 10127.696169114939, 5472.781088756406, 5495.203195536625, 5515.448455154533, 5519.144532597454, 5519.39286678465, 4472.217361961643, 4691.859244222679, 4843.346148269939, 4844.4186697596515, 5015.247712843103, 4056.8288485606963, 4069.491313585802, 4074.022234663719, 4124.443200167939, 4127.6904232182105, 1154.7575009248153, 1366.4192718094969, 1371.5062577472102, 1373.169232614274, 1378.6691194113341, 737.9262073792578, 759.3469616130124, 791.8653828849109, 934.3552948465673, 958.9808203835884, 5535.557155427637, 5672.716364181857, 5716.399778023331, 5976.967830937502, 6041.395860413923, 987.2151639446657, 1009.5204777730667, 1021.8416145497459, 1024.4877561219391, 1058.2063452410691, 3770.7360484765804, 3917.353636649978, 3956.347202711578, 3972.0, 4013.1572369802084, 10312.690619281433, 10804.1896857736, 10884.782304353868, 10898.673039808818, 10946.288491248044, 10575.15398768112, 10741.40922249268, 10824.837627435594, 10859.936612043686, 10886.23796334249, 8172.060213075572, 8330.583470826434, 8364.790880227922, 8376.832463350698, 8419.36854735885, 5553.972230138985, 5648.915266271091, 5676.290463692038, 5742.971285143548, 5752.539796816161, 9936.75158121024, 10017.0, 10068.899311006797, 10100.343477673927, 10141.79716405653, 9776.608935642778, 9953.701388958343, 10177.796444071226, 10339.172866170538, 10350.22373322019, 5747.396523365153, 7317.609654165692, 7497.175310715707, 7559.3196612305155, 7570.16728159913, 8397.412181147261, 8414.368922330726, 8444.986601607847, 8467.407281490237, 8477.194666506672, 7291.927080729125, 7386.593737344498, 7549.584772837547, 7611.809704757426, 7618.047744031996, 7169.677364518505, 7179.641017948978, 7290.9635451822405, 7384.409247260099, 7460.0, 7001.299870013162, 7090.503664743418, 7092.503524753218, 7103.82240443993, 7105.644717764195, 3943.467631869661, 4116.217918595457, 4847.927281090716, 4907.214845624683, 5087.949120508748, 4967.05625931072, 4968.527989841104, 4979.975100124476, 5012.172991456368, 5039.017391608601], "value": 4639.934711308692, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [313.43112251263955, 316.9508726147447, 324.55860030358707, 330.16962339715604, 332.11491375484337, 4616.515265897083, 5489.505944464997, 6103.145559621651, 6152.630842149474, 6165.290991535969, 4944.87637809054, 5956.0, 6044.879102417949, 6067.421345927985, 6068.544859135572, 3908.433277174808, 4146.771927543984, 4481.91036179277, 4526.8868278292975, 4555.271156614942, 3652.9452058219017, 3930.6658933990643, 4455.732656040632, 4505.8422955196575, 4522.6155776759015, 4337.587929146726, 4414.646828253732, 4414.646828253732, 4420.248557745191, 4432.268675668511, 4416.381706561084, 4425.3140763181755, 4428.3800267962515, 4429.0, 4468.218061839198, 2993.7006299369887, 3754.1365485938113, 3776.470529435583, 3815.1606646537716, 3821.9426708599394, 5289.709066001368, 5654.660720356792, 5665.2351932489, 5670.1571989103295, 5708.546329470693, 4251.893702657459, 4399.692021558492, 4865.367502224688, 4932.977479233497, 5096.362954630671, 5453.863653408657, 6318.0, 6355.682215889234, 6382.457491113261, 6440.4847612190915, 3688.428293614494, 4069.8604390770633, 4085.59144085592, 4240.1307731914885, 4244.257254980397, 6198.318184999646, 6776.7628133015105, 6805.965970170167, 6812.897806532906, 6828.624425656587, 3613.367660659374, 4008.599140086026, 4220.388043733641, 4306.82772689089, 4344.913101737948, 3640.38003088623, 4300.677449191323, 4308.4399028126145, 4336.956630433719, 4366.8034938427845, 4453.819737769493, 4717.905641887143, 4725.881852953704, 5107.770150343244, 5119.513646203604, 5354.464553544614, 5844.649321040752, 6239.376062393815, 6462.450691691259, 6476.0, 1248.925064496115, 1480.5558332500207, 1494.3275526013338, 1496.5585152380074, 1501.7672260799593, 949.5110018340561, 965.724768441005, 977.731123940922, 983.6508039645822, 990.5294984882123, 5364.892702145935, 5847.298324201123, 5902.055671092605, 5946.0, 5955.374685657944, 6557.606543607399, 6582.354411397151, 6605.207375115017, 6608.735650574019, 6623.172103487064, 5613.522850557748, 5688.591341710207, 5854.882902341929, 5871.412858714094, 5882.794102206402, 3434.587849458081, 3465.6880880720855, 3467.358538670354, 3490.755647104729, 3495.3358861816173, 6696.665166741741, 6769.458443324522, 6868.793936181923, 6878.828029299308, 6893.4140598048725, 6188.793185328907, 6428.357164283628, 6774.45804335652, 6797.218319893177, 6823.624700641512, 5821.53427725781, 6240.781572645026, 6342.255879633138, 6794.490413219029, 7133.429325654039, 6712.899306510309, 6728.64497425897, 6734.831629209309, 6784.0, 6784.864302714017, 5746.913796292975, 5892.733062391607, 6649.634270114997, 6657.833554161185, 6676.899846502403, 5268.025415298182, 6613.371729685672, 6619.66901654925, 6623.602583844985, 6879.385833383282, 3851.7496362736333, 3942.5860284669698, 4260.169266992907, 4285.335772955197, 4294.334378171388, 6036.456718895321, 6550.115734375799, 6559.114519539801, 6562.80311590653, 6606.834829129118, 4994.875128121826, 5852.0, 6289.056641503811, 6396.680165991775, 6411.51913606472, 5130.51260130287, 5998.730057147526, 6166.753329866755, 6469.385408386196, 6512.078936713776, 5117.334746482933, 6070.696465176812, 6077.787277445268, 6372.458341041063, 6384.201974753156, 6410.903836442364, 6482.35176482348, 6486.6648006716605, 6493.448056915215, 6496.480281577368, 5413.7834486620095, 6226.50187984951, 6361.586496877688, 6370.872582548416, 6384.106225128393], "value": 4579.8485621643895, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [673.4343151752529, 677.3091446724342, 686.3719696477723, 750.5271678842329, 754.8980887580175, 14953.551393458187, 15071.773923391143, 15099.849001509981, 16780.58048548787, 17499.475015749547, 16139.838601613981, 16501.17494125292, 16674.66650666986, 16702.83297167028, 16739.581510462245, 722.1009842745789, 915.3318077803208, 923.0169869089432, 924.2328867040374, 926.0322962504199, 875.2166810704416, 883.995580022099, 904.6336233825311, 917.1882883647953, 917.9357444978854, 901.6528636474939, 907.6006557114881, 909.1090731083542, 913.8949020862586, 919.4345477531303, 869.2784988459595, 895.9641614335484, 912.4251721415532, 914.8444764390036, 919.1360121485816, 644.8323435906651, 661.9205695316545, 664.6012392564422, 670.4669787518934, 674.5817593092314, 13538.932305338509, 13574.796378054338, 13663.111897726616, 13663.248521331323, 13670.863291367157, 12470.314132722697, 12478.50085996559, 12593.433295501725, 12671.303078330688, 12810.679733006657, 9967.40195588267, 9976.551055202533, 10186.796264074677, 10285.331453455576, 10746.623868164577, 3531.715504580739, 3584.6773790358993, 3629.94555081674, 3717.8141092945516, 3793.336166170937, 62982.0, 80085.59957200235, 81569.59215203945, 83162.0, 85450.5727471365, 9561.61753529851, 9616.807663846825, 9660.855087173699, 9744.220462362993, 9973.950130249303, 20895.8955205223, 21670.24154154597, 21801.90990450471, 21838.781612183673, 21881.234156804436, 6034.728437220336, 7541.7360392386, 7737.690492380354, 7775.805604859811, 7837.686492540234, 4059.5331536873045, 4158.480189976253, 4248.681348898845, 4250.192463431938, 4306.80619372129, 3557.3774589446743, 3660.213054193361, 3661.9267614648093, 3663.065918190844, 3664.8272552783387, 651.8956966885371, 653.7777155767062, 657.2901266632026, 661.3882159002903, 662.9635370054693, 549.0720682047328, 571.5397160255504, 580.364019418336, 615.5414216408805, 616.5191150902303, 7651.196624354474, 7760.146383897767, 8002.639881205359, 8021.598920053974, 8072.233137852012, 585.9941400586025, 589.3046205477577, 606.6875559087074, 623.2396476298908, 631.1952260867431, 2893.4068515954177, 3536.876209332661, 3621.6559426854406, 3807.9048023799073, 3939.9015024624614, 72475.0, 88999.55500222457, 91918.54040729754, 92764.53617731867, 94559.0, 76147.61926190334, 76842.23157768462, 77366.0, 78659.0, 79414.0, 67973.0, 68469.65765171242, 69036.30963690298, 69930.9510357345, 70292.94560581596, 7446.553206807609, 7625.466217364731, 7667.0, 7703.730369437043, 7811.89504720495, 32878.34243315073, 42570.78714606531, 42751.3587296197, 43860.561394385644, 44675.0, 59170.40829591649, 61727.0, 64788.67605661941, 64804.0, 65062.34937650563, 9711.562979666074, 9714.951425242829, 9784.951075244579, 10000.449975251431, 10428.530716117944, 10860.076893464144, 11183.496742646763, 11195.552177912792, 11238.887611124112, 11272.774544509228, 10131.7467063324, 10142.746431339276, 10226.130778883879, 10239.385636861813, 10797.838032429678, 10261.58953641846, 10268.281220314806, 10291.43397113136, 10320.896791031993, 10646.095081918123, 9950.601975920883, 10037.456899759556, 10084.0, 10377.533011014673, 10466.63366782159, 8633.30933525329, 8664.436811607226, 8832.735017949472, 9095.363324567217, 9151.588178532114, 8093.5953202340825, 8665.17680820321, 8750.212480876751, 8872.556372181236, 8973.910260897308], "value": 6704.694056459697, "unit": "score"}], "name": "dromaeo_css", "value": 7198.4127143545375, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 151.0, 116.0, 117.0, 154.0, 158.0, 118.0, 130.0, 169.0, 120.0], "value": 136.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 156.0, 150.0, 198.0, 149.0, 134.0, 139.0, 128.0, 188.0, 115.0], "value": 153.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [242.0, 256.0, 201.0, 194.0, 240.0, 202.0, 264.0, 277.0, 230.0, 205.0], "value": 231.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 82.0, 74.0, 169.0, 46.0, 52.0, 90.0, 94.0, 153.0, 58.0], "value": 88.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 127.0, 179.0, 75.0, 83.0, 82.0, 123.0, 77.0, 66.0, 146.0], "value": 104.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 88.0, 90.0, 89.0, 91.0, 87.0, 92.0, 89.0, 91.0, 87.0], "value": 89.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 97.0, 96.0, 97.0, 130.0, 103.0, 153.0, 117.0, 96.0], "value": 108.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 102.0, 138.0, 90.0, 93.0, 91.0, 91.0, 95.0, 109.0, 91.0], "value": 99.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 59.0, 59.0, 63.0, 63.0, 59.0, 76.0, 62.0, 59.0], "value": 62.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 96.0, 105.0, 103.0, 104.0, 73.0, 73.0, 67.0, 69.0, 69.0], "value": 82.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 89.0, 88.0, 85.0, 86.0, 90.0, 90.0, 89.0, 87.0], "value": 87.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 171.0, 166.0, 167.0, 142.0, 156.0, 169.0, 156.0, 203.0, 195.0], "value": 166.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 172.0, 173.0, 178.0, 214.0, 230.0, 230.0, 183.0, 168.0, 172.0], "value": 189.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 72.0, 69.0, 67.0, 76.0, 69.0, 76.0, 75.0, 72.0, 71.0], "value": 71.5, "unit": "score"}], "name": "kraken", "value": 1670.5000000000002, "alertThreshold": 2.0}]} 05:08:54 INFO - Return code: 0 05:08:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 05:08:54 ERROR - # TBPL SUCCESS # 05:08:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:08:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:08:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:08:54 INFO - 2016-04-15 05:08:54.217 screenresolution[1682:10524] starting screenresolution argv=screenresolution get 05:08:54 INFO - 2016-04-15 05:08:54.220 screenresolution[1682:10524] Display 0: 1600x1200x32@60 05:08:54 INFO - 2016-04-15 05:08:54.225 screenresolution[1683:10527] starting screenresolution argv=screenresolution list 05:08:54 INFO - Available Modes on Display 0 05:08:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:08:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:08:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:08:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:08:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:08:54 INFO - Intel Iris: 05:08:54 INFO - Chipset Model: Intel Iris 05:08:54 INFO - Type: GPU 05:08:54 INFO - Bus: Built-In 05:08:54 INFO - VRAM (Dynamic, Max): 1536 MB 05:08:54 INFO - Vendor: Intel (0x8086) 05:08:54 INFO - Device ID: 0x0a2e 05:08:54 INFO - Revision ID: 0x0009 05:08:54 INFO - Displays: 05:08:54 INFO - Display: 05:08:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:08:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:08:54 INFO - Display Serial Number: 1600x1200 60 05:08:54 INFO - Main Display: Yes 05:08:54 INFO - Mirror: Off 05:08:54 INFO - Online: Yes 05:08:54 INFO - Rotation: Supported 05:08:54 INFO - Return code: 0 05:08:54 INFO - Running post-action listener: _resource_record_post_action 05:08:54 INFO - Running post-run listener: _resource_record_post_run 05:08:55 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 37676544; Write bytes: 695448576; Read time: 393; Write time: 1946 05:08:55 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 395098624; Write bytes: 395855360; Read time: 29688; Write time: 707 05:08:55 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 35849728; Write bytes: 298745344; Read time: 374; Write time: 1236 05:08:55 INFO - Running post-run listener: _upload_blobber_files 05:08:55 INFO - Blob upload gear active. 05:08:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:08:55 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:08:55 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:08:55 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:08:55 INFO - (blobuploader) - INFO - Open directory for files ... 05:08:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:08:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:08:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:08:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:08:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:08:56 INFO - (blobuploader) - INFO - Done attempting. 05:08:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:08:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:08:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:08:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:08:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:08:57 INFO - (blobuploader) - INFO - Done attempting. 05:08:57 INFO - (blobuploader) - INFO - Iteration through files over. 05:08:57 INFO - Return code: 0 05:08:57 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:08:57 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:08:57 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fa66e8cb56cb936392106a3e5f4ff4d488eecdb3a2f2529e90d22d160153a442e6b3e88319bc7226f8deca9c7dfd5bd99a6b2b153584b7b4942f46b88dbd1234", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/64bc953376274448b24f2bd42d5eb10616b24a490d7aa09bbf17dc80ceec02b4dbd652d766713d35bbde30446a1906a8e2040de6c02b85b71adbd52343719bfd"} 05:08:57 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:08:57 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:08:57 INFO - Contents: 05:08:57 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fa66e8cb56cb936392106a3e5f4ff4d488eecdb3a2f2529e90d22d160153a442e6b3e88319bc7226f8deca9c7dfd5bd99a6b2b153584b7b4942f46b88dbd1234", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/64bc953376274448b24f2bd42d5eb10616b24a490d7aa09bbf17dc80ceec02b4dbd652d766713d35bbde30446a1906a8e2040de6c02b85b71adbd52343719bfd"} 05:08:57 INFO - Running post-run listener: copy_logs_to_upload_dir 05:08:57 INFO - Copying logs to upload dir... 05:08:57 INFO - mkdir: /builds/slave/test/build/upload/logs 05:08:57 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.839833 ========= master_lag: 7.63 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-04-15 05:09:05.310157) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 8 secs) (at 2016-04-15 05:09:05.319840) ========= 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.FZOqlnWUfg/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.QZNBpnQxcO/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fa66e8cb56cb936392106a3e5f4ff4d488eecdb3a2f2529e90d22d160153a442e6b3e88319bc7226f8deca9c7dfd5bd99a6b2b153584b7b4942f46b88dbd1234", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/64bc953376274448b24f2bd42d5eb10616b24a490d7aa09bbf17dc80ceec02b4dbd652d766713d35bbde30446a1906a8e2040de6c02b85b71adbd52343719bfd"} build_url:https://queue.taskcluster.net/v1/task/PlPF6wH1SqurAMmEVhKS_g/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011206 build_url: 'https://queue.taskcluster.net/v1/task/PlPF6wH1SqurAMmEVhKS_g/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fa66e8cb56cb936392106a3e5f4ff4d488eecdb3a2f2529e90d22d160153a442e6b3e88319bc7226f8deca9c7dfd5bd99a6b2b153584b7b4942f46b88dbd1234", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/64bc953376274448b24f2bd42d5eb10616b24a490d7aa09bbf17dc80ceec02b4dbd652d766713d35bbde30446a1906a8e2040de6c02b85b71adbd52343719bfd"}' ========= master_lag: 8.28 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 8 secs) (at 2016-04-15 05:09:13.606800) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-15 05:09:13.607133) ========= 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.FZOqlnWUfg/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.QZNBpnQxcO/Listeners TMPDIR=/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/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.005089 ========= master_lag: 0.64 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-15 05:09:14.257035) ========= ========= Started reboot skipped (results: 3, elapsed: 6 secs) (at 2016-04-15 05:09:14.257368) ========= ========= Finished reboot skipped (results: 3, elapsed: 6 secs) (at 2016-04-15 05:09:20.471178) ========= ========= Total master_lag: 16.61 =========