builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0108 starttime: 1460699537.18 results: success (0) buildid: 20160414215439 builduid: 67dc8b8a396a42e3b81df410dcf9f312 revision: 7b9fbf34a1d6c4a4a54486b64f2be9c8b304401d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-14 22:52:17.176818) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-14 22:52:17.177290) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 7 secs) (at 2016-04-14 22:52:17.177580) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 7 secs) (at 2016-04-14 22:52:24.881857) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-14 22:52:24.882120) ========= 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.ue6CevGy7Z/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.bYy3CyWRFS/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-14 22:52:25-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 145M=0s 2016-04-14 22:52:25 (145 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.109345 ========= master_lag: 2.21 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-14 22:52:27.199080) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 22:52:27.199533) ========= 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.ue6CevGy7Z/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.bYy3CyWRFS/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.026069 ========= master_lag: 0.41 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-14 22:52:27.636532) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-14 22:52:27.636903) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 7b9fbf34a1d6c4a4a54486b64f2be9c8b304401d --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 7b9fbf34a1d6c4a4a54486b64f2be9c8b304401d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ue6CevGy7Z/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.bYy3CyWRFS/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-14 22:52:28,717 truncating revision to first 12 chars 2016-04-14 22:52:28,717 Setting DEBUG logging. 2016-04-14 22:52:28,717 attempt 1/10 2016-04-14 22:52:28,717 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/7b9fbf34a1d6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-14 22:52:29,680 unpacking tar archive at: fx-team-7b9fbf34a1d6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.216635 ========= master_lag: 1.36 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-14 22:52:30.212497) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-14 22:52:30.212800) ========= script_repo_revision: 7b9fbf34a1d6c4a4a54486b64f2be9c8b304401d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-14 22:52:30.218242) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2016-04-14 22:52:30.218546) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2016-04-14 22:52:32.043008) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-04-14 22:52:32.043314) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.ue6CevGy7Z/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.bYy3CyWRFS/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 22:52:32 INFO - MultiFileLogger online at 20160414 22:52:32 in /builds/slave/test 22:52:32 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 22:52:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:52:32 INFO - {'append_to_log': False, 22:52:32 INFO - 'base_work_dir': '/builds/slave/test', 22:52:32 INFO - 'blob_upload_branch': 'Fx-Team', 22:52:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:52:32 INFO - 'branch': 'Fx-Team', 22:52:32 INFO - 'buildbot_json_path': 'buildprops.json', 22:52:32 INFO - 'config_files': ('talos/mac_config.py',), 22:52:32 INFO - 'default_actions': ('clobber', 22:52:32 INFO - 'read-buildbot-config', 22:52:32 INFO - 'download-and-extract', 22:52:32 INFO - 'populate-webroot', 22:52:32 INFO - 'create-virtualenv', 22:52:32 INFO - 'install', 22:52:32 INFO - 'run-tests'), 22:52:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:52:32 INFO - 'download_minidump_stackwalk': True, 22:52:32 INFO - 'download_symbols': 'ondemand', 22:52:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:52:32 INFO - 'tooltool.py': '/tools/tooltool.py', 22:52:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:52:32 INFO - '/tools/misc-python/virtualenv.py')}, 22:52:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:52:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:52:32 INFO - 'installer_path': 'installer.exe', 22:52:32 INFO - 'log_level': 'info', 22:52:32 INFO - 'log_name': 'talos', 22:52:32 INFO - 'log_to_console': True, 22:52:32 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:52:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:52:32 INFO - 'opt_config_files': (), 22:52:32 INFO - 'pip_index': False, 22:52:32 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:52:32 INFO - 'cmd': ('bash', 22:52:32 INFO - '-c', 22:52:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:52:32 INFO - 'enabled': True, 22:52:32 INFO - 'halt_on_failure': False, 22:52:32 INFO - 'name': 'check_screen_resolution'},), 22:52:32 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:52:32 INFO - 'cmd': ('bash', 22:52:32 INFO - '-c', 22:52:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:52:32 INFO - 'enabled': True, 22:52:32 INFO - 'halt_on_failure': False, 22:52:32 INFO - 'name': 'check_screen_resolution'},), 22:52:32 INFO - 'run_cmd_checks_enabled': True, 22:52:32 INFO - 'sps_profile': False, 22:52:32 INFO - 'sps_profile_interval': 0, 22:52:32 INFO - 'suite': 'dromaeojs', 22:52:32 INFO - 'system_bits': '32', 22:52:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:52:32 INFO - 'title': 't-yosemite-r7-0108', 22:52:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:52:32 INFO - 'use_talos_json': True, 22:52:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:52:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:52:32 INFO - 'work_dir': 'build'} 22:52:32 INFO - ##### 22:52:32 INFO - ##### Running clobber step. 22:52:32 INFO - ##### 22:52:32 INFO - Running pre-action listener: _resource_record_pre_action 22:52:32 INFO - Running main action method: clobber 22:52:32 INFO - rmtree: /builds/slave/test/build 22:52:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:52:33 INFO - Running post-action listener: _resource_record_post_action 22:52:33 INFO - ##### 22:52:33 INFO - ##### Running read-buildbot-config step. 22:52:33 INFO - ##### 22:52:33 INFO - Running pre-action listener: _resource_record_pre_action 22:52:33 INFO - Running main action method: read_buildbot_config 22:52:33 INFO - Using buildbot properties: 22:52:33 INFO - { 22:52:33 INFO - "project": "", 22:52:33 INFO - "product": "firefox", 22:52:33 INFO - "script_repo_revision": "production", 22:52:33 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 22:52:33 INFO - "repository": "", 22:52:33 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 22:52:33 INFO - "buildid": "20160414215439", 22:52:33 INFO - "pgo_build": "False", 22:52:33 INFO - "basedir": "/builds/slave/test", 22:52:33 INFO - "buildnumber": 726, 22:52:33 INFO - "slavename": "t-yosemite-r7-0108", 22:52:33 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 22:52:33 INFO - "platform": "yosemite_r7", 22:52:33 INFO - "scheduler": "tests-fx-team-macosx64-talos", 22:52:33 INFO - "branch": "fx-team", 22:52:33 INFO - "repo_path": "integration/fx-team", 22:52:33 INFO - "revision": "7b9fbf34a1d6c4a4a54486b64f2be9c8b304401d", 22:52:33 INFO - "stage_platform": "macosx64", 22:52:33 INFO - "builduid": "67dc8b8a396a42e3b81df410dcf9f312", 22:52:33 INFO - "slavebuilddir": "test" 22:52:33 INFO - } 22:52:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/VP6EYMVjQjed8ZKaDJkvEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 22:52:33 INFO - Running post-action listener: _resource_record_post_action 22:52:33 INFO - ##### 22:52:33 INFO - ##### Running download-and-extract step. 22:52:33 INFO - ##### 22:52:33 INFO - Running pre-action listener: _resource_record_pre_action 22:52:33 INFO - Running main action method: download_and_extract 22:52:33 INFO - mkdir: /builds/slave/test/build/tests 22:52:33 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')]} 22:52:33 INFO - https://queue.taskcluster.net/v1/task/VP6EYMVjQjed8ZKaDJkvEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 22:52:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VP6EYMVjQjed8ZKaDJkvEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 22:52:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VP6EYMVjQjed8ZKaDJkvEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 22:52:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VP6EYMVjQjed8ZKaDJkvEQ/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 22:52:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VP6EYMVjQjed8ZKaDJkvEQ/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 22:52:40 INFO - Downloaded 1199 bytes. 22:52:40 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 22:52:40 INFO - Using the following test package requirements: 22:52:40 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 22:52:40 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:52:40 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 22:52:40 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:52:40 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 22:52:40 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:52:40 INFO - u'jsshell-mac.zip'], 22:52:40 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:52:40 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 22:52:40 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 22:52:40 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:52:40 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 22:52:40 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:52:40 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 22:52:40 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:52:40 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 22:52:40 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 22:52:40 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 22:52:40 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:52: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')]} 22:52:40 INFO - https://queue.taskcluster.net/v1/task/VP6EYMVjQjed8ZKaDJkvEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:52:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VP6EYMVjQjed8ZKaDJkvEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 22:52:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VP6EYMVjQjed8ZKaDJkvEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 22:52:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VP6EYMVjQjed8ZKaDJkvEQ/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 22:52:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VP6EYMVjQjed8ZKaDJkvEQ/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 22:52:41 INFO - Downloaded 18031162 bytes. 22:52:41 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'] 22:52:41 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 22:52:45 INFO - Return code: 0 22:52:45 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 22:52:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:52:45 INFO - https://queue.taskcluster.net/v1/task/VP6EYMVjQjed8ZKaDJkvEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:52:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VP6EYMVjQjed8ZKaDJkvEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 22:52:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VP6EYMVjQjed8ZKaDJkvEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 22:52:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VP6EYMVjQjed8ZKaDJkvEQ/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 22:52:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VP6EYMVjQjed8ZKaDJkvEQ/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 22:52:49 INFO - Downloaded 18031162 bytes. 22:52:49 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'] 22:52:49 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 22:52:52 INFO - Return code: 0 22:52:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:52:52 INFO - https://queue.taskcluster.net/v1/task/VP6EYMVjQjed8ZKaDJkvEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 22:52:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VP6EYMVjQjed8ZKaDJkvEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 22:52:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VP6EYMVjQjed8ZKaDJkvEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 22:52:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VP6EYMVjQjed8ZKaDJkvEQ/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 22:52:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VP6EYMVjQjed8ZKaDJkvEQ/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 22:52:54 INFO - Downloaded 10841076 bytes. 22:52:54 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'] 22:52:54 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 22:52:54 INFO - Return code: 0 22:52:54 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')]} 22:52:54 INFO - https://queue.taskcluster.net/v1/task/VP6EYMVjQjed8ZKaDJkvEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 22:52:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VP6EYMVjQjed8ZKaDJkvEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 22:52:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VP6EYMVjQjed8ZKaDJkvEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 22:52:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VP6EYMVjQjed8ZKaDJkvEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 22:52:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VP6EYMVjQjed8ZKaDJkvEQ/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 22:52:58 INFO - Downloaded 109832690 bytes. 22:52:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VP6EYMVjQjed8ZKaDJkvEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 22:52:58 INFO - mkdir: /builds/slave/test/properties 22:52:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:52:58 INFO - Writing to file /builds/slave/test/properties/build_url 22:52:58 INFO - Contents: 22:52:58 INFO - build_url:https://queue.taskcluster.net/v1/task/VP6EYMVjQjed8ZKaDJkvEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 22:52:59 INFO - Running post-action listener: _resource_record_post_action 22:52:59 INFO - Running post-action listener: set_extra_try_arguments 22:52:59 INFO - ##### 22:52:59 INFO - ##### Running populate-webroot step. 22:52:59 INFO - ##### 22:52:59 INFO - Running pre-action listener: _resource_record_pre_action 22:52:59 INFO - Running main action method: populate_webroot 22:52:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:52:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:52:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:52:59 INFO - '--tppagecycles', 22:52:59 INFO - '7'], 22:52:59 INFO - 'tests': ['tsvgm']}}, 22:52:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:52:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:52:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:52:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:52:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:52:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:52:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:52:59 INFO - 'talos_options': ['--disable-e10s'], 22:52:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:52:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:52:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:52:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:52:59 INFO - 'talos_options': ['--disable-e10s'], 22:52:59 INFO - 'tests': ['damp', 'tps']}, 22:52:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:52:59 INFO - 'tests': ['damp', 'tps']}, 22:52:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:52:59 INFO - 'tests': ['dromaeo_dom']}, 22:52:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:52:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:52:59 INFO - 'tests': ['a11yr', 22:52:59 INFO - 'ts_paint', 22:52:59 INFO - 'tpaint', 22:52:59 INFO - 'sessionrestore', 22:52:59 INFO - 'sessionrestore_no_auto_restore', 22:52:59 INFO - 'tabpaint']}, 22:52:59 INFO - 'other-e10s': {'tests': ['a11yr', 22:52:59 INFO - 'ts_paint', 22:52:59 INFO - 'tpaint', 22:52:59 INFO - 'sessionrestore', 22:52:59 INFO - 'sessionrestore_no_auto_restore', 22:52:59 INFO - 'tabpaint']}, 22:52:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:52:59 INFO - 'ts_paint', 22:52:59 INFO - 'tpaint', 22:52:59 INFO - 'sessionrestore', 22:52:59 INFO - 'sessionrestore_no_auto_restore', 22:52:59 INFO - 'tabpaint']}, 22:52:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:52:59 INFO - 'ts_paint', 22:52:59 INFO - 'tpaint', 22:52:59 INFO - 'sessionrestore', 22:52:59 INFO - 'sessionrestore_no_auto_restore', 22:52:59 INFO - 'tabpaint']}, 22:52:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:52:59 INFO - 'tests': ['a11yr', 22:52:59 INFO - 'ts_paint', 22:52:59 INFO - 'tpaint', 22:52:59 INFO - 'sessionrestore', 22:52:59 INFO - 'sessionrestore_no_auto_restore', 22:52:59 INFO - 'tabpaint']}, 22:52:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:52:59 INFO - 'tests': ['a11yr', 22:52:59 INFO - 'ts_paint', 22:52:59 INFO - 'tpaint', 22:52:59 INFO - 'sessionrestore', 22:52:59 INFO - 'sessionrestore_no_auto_restore', 22:52:59 INFO - 'tabpaint']}, 22:52:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:52:59 INFO - 'tests': ['tsvgx', 22:52:59 INFO - 'tsvgr_opacity', 22:52:59 INFO - 'tart', 22:52:59 INFO - 'tscrollx', 22:52:59 INFO - 'cart']}, 22:52:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:52:59 INFO - 'tsvgr_opacity', 22:52:59 INFO - 'tart', 22:52:59 INFO - 'tscrollx', 22:52:59 INFO - 'cart']}, 22:52:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:52:59 INFO - 'talos_options': ['--disable-e10s'], 22:52:59 INFO - 'tests': ['tp5o']}, 22:52:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:52:59 INFO - 'tests': ['tp5o']}, 22:52:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:52:59 INFO - 'talos_options': ['--disable-e10s', 22:52:59 INFO - '--xperf_path', 22:52:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:52:59 INFO - 'tests': ['tp5n']}, 22:52:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:52:59 INFO - 'talos_options': ['--xperf_path', 22:52:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:52:59 INFO - 'tests': ['tp5n']}}, 22:52:59 INFO - 'talos.zip': {'path': '', 22:52:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:52:59 INFO - Running post-action listener: _resource_record_post_action 22:52:59 INFO - ##### 22:52:59 INFO - ##### Running create-virtualenv step. 22:52:59 INFO - ##### 22:52:59 INFO - Running pre-action listener: _resource_record_pre_action 22:52:59 INFO - Running main action method: create_virtualenv 22:52:59 INFO - Creating virtualenv /builds/slave/test/build/venv 22:52:59 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 22:52:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:52:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:52:59 INFO - Using real prefix '/tools/python27' 22:52:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:53:00 INFO - Installing distribute.............................................................................................................................................................................................done. 22:53:03 INFO - Installing pip.................done. 22:53:03 INFO - Return code: 0 22:53:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:53:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:53:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:53:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:53:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:53:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ab8da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab9d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c2a6d2c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa2ce00>, '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 0x10a855db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa27b20>, '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.ue6CevGy7Z/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.bYy3CyWRFS/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 22:53:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:53:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:53:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ue6CevGy7Z/Render', 22:53:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:53:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:53:03 INFO - 'HOME': '/Users/cltbld', 22:53:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:53:03 INFO - 'LOGNAME': 'cltbld', 22:53:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:53:03 INFO - 'MOZ_NO_REMOTE': '1', 22:53:03 INFO - 'NO_EM_RESTART': '1', 22:53:03 INFO - 'PAGER': '/bin/cat', 22:53:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:53:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:53:03 INFO - 'PWD': '/builds/slave/test', 22:53:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:53:03 INFO - 'SHELL': '/bin/bash', 22:53:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bYy3CyWRFS/Listeners', 22:53:03 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 22:53:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:53:03 INFO - 'USER': 'cltbld', 22:53:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:53:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:53:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:53:03 INFO - 'XPC_FLAGS': '0x0', 22:53:03 INFO - 'XPC_SERVICE_NAME': '0', 22:53:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:53:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:53:03 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 22:53:03 INFO - Cleaning up... 22:53:03 INFO - Return code: 0 22:53:03 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 22:53:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:53:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:53:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:53:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:53:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ab8da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab9d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c2a6d2c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa2ce00>, '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 0x10a855db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa27b20>, '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.ue6CevGy7Z/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.bYy3CyWRFS/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 22:53:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 22:53:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 22:53:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ue6CevGy7Z/Render', 22:53:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:53:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:53:03 INFO - 'HOME': '/Users/cltbld', 22:53:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:53:03 INFO - 'LOGNAME': 'cltbld', 22:53:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:53:03 INFO - 'MOZ_NO_REMOTE': '1', 22:53:03 INFO - 'NO_EM_RESTART': '1', 22:53:03 INFO - 'PAGER': '/bin/cat', 22:53:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:53:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:53:03 INFO - 'PWD': '/builds/slave/test', 22:53:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:53:03 INFO - 'SHELL': '/bin/bash', 22:53:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bYy3CyWRFS/Listeners', 22:53:03 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 22:53:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:53:03 INFO - 'USER': 'cltbld', 22:53:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:53:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:53:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:53:03 INFO - 'XPC_FLAGS': '0x0', 22:53:03 INFO - 'XPC_SERVICE_NAME': '0', 22:53:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:53:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:53:03 INFO - Downloading/unpacking psutil>=0.7.1 22:53:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:53:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:53:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:53:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:53:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:53:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:53:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:53:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:53:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:53:06 INFO - Installing collected packages: psutil 22:53:06 INFO - Running setup.py install for psutil 22:53:06 INFO - building 'psutil._psutil_osx' extension 22:53:06 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 22:53:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 22:53:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 22:53:07 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 22:53:07 INFO - building 'psutil._psutil_posix' extension 22:53:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 22:53:07 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:53:07 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:53:07 INFO - ^ 22:53:07 INFO - 1 warning generated. 22:53:07 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 22:53:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:53:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:53:07 INFO - Successfully installed psutil 22:53:07 INFO - Cleaning up... 22:53:07 INFO - Return code: 0 22:53:07 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 22:53:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:53:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:53:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:53:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:53:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ab8da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab9d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c2a6d2c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa2ce00>, '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 0x10a855db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa27b20>, '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.ue6CevGy7Z/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.bYy3CyWRFS/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 22:53:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 22:53:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 22:53:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ue6CevGy7Z/Render', 22:53:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:53:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:53:07 INFO - 'HOME': '/Users/cltbld', 22:53:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:53:07 INFO - 'LOGNAME': 'cltbld', 22:53:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:53:07 INFO - 'MOZ_NO_REMOTE': '1', 22:53:07 INFO - 'NO_EM_RESTART': '1', 22:53:07 INFO - 'PAGER': '/bin/cat', 22:53:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:53:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:53:07 INFO - 'PWD': '/builds/slave/test', 22:53:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:53:07 INFO - 'SHELL': '/bin/bash', 22:53:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bYy3CyWRFS/Listeners', 22:53:07 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 22:53:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:53:07 INFO - 'USER': 'cltbld', 22:53:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:53:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:53:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:53:07 INFO - 'XPC_FLAGS': '0x0', 22:53:07 INFO - 'XPC_SERVICE_NAME': '0', 22:53:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:53:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:53:08 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 22:53:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:53:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:53:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:53:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:53:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:53:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:53:10 INFO - Downloading mozsystemmonitor-0.0.tar.gz 22:53:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:53:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 22:53:10 INFO - Installing collected packages: mozsystemmonitor 22:53:10 INFO - Running setup.py install for mozsystemmonitor 22:53:10 INFO - Successfully installed mozsystemmonitor 22:53:10 INFO - Cleaning up... 22:53:10 INFO - Return code: 0 22:53:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:53:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:53:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:53:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:53:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:53:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ab8da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab9d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c2a6d2c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa2ce00>, '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 0x10a855db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa27b20>, '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.ue6CevGy7Z/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.bYy3CyWRFS/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 22:53:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:53:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:53:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ue6CevGy7Z/Render', 22:53:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:53:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:53:10 INFO - 'HOME': '/Users/cltbld', 22:53:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:53:10 INFO - 'LOGNAME': 'cltbld', 22:53:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:53:10 INFO - 'MOZ_NO_REMOTE': '1', 22:53:10 INFO - 'NO_EM_RESTART': '1', 22:53:10 INFO - 'PAGER': '/bin/cat', 22:53:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:53:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:53:10 INFO - 'PWD': '/builds/slave/test', 22:53:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:53:10 INFO - 'SHELL': '/bin/bash', 22:53:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bYy3CyWRFS/Listeners', 22:53:10 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 22:53:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:53:10 INFO - 'USER': 'cltbld', 22:53:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:53:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:53:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:53:10 INFO - 'XPC_FLAGS': '0x0', 22:53:10 INFO - 'XPC_SERVICE_NAME': '0', 22:53:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:53:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:53:11 INFO - Downloading/unpacking blobuploader==1.2.4 22:53:11 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 22:53:11 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 22:53:11 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 22:53:11 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 22:53:11 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 22:53:11 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 22:53:13 INFO - Downloading blobuploader-1.2.4.tar.gz 22:53:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:53:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:53:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:53:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:53:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:53:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:53:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:53:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:53:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:53:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:53: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 22:53: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 22:53: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 22:53: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 22:53: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 22:53: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 22:53:14 INFO - Downloading docopt-0.6.1.tar.gz 22:53:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:53:14 INFO - Installing collected packages: blobuploader, requests, docopt 22:53:14 INFO - Running setup.py install for blobuploader 22:53:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:53:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:53:15 INFO - Running setup.py install for requests 22:53:15 INFO - Running setup.py install for docopt 22:53:15 INFO - Successfully installed blobuploader requests docopt 22:53:15 INFO - Cleaning up... 22:53:15 INFO - Return code: 0 22:53:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:53:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:53:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:53:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:53:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:53:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ab8da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab9d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c2a6d2c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa2ce00>, '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 0x10a855db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa27b20>, '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.ue6CevGy7Z/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.bYy3CyWRFS/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 22:53:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:53:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:53:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ue6CevGy7Z/Render', 22:53:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:53:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:53:15 INFO - 'HOME': '/Users/cltbld', 22:53:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:53:15 INFO - 'LOGNAME': 'cltbld', 22:53:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:53:15 INFO - 'MOZ_NO_REMOTE': '1', 22:53:15 INFO - 'NO_EM_RESTART': '1', 22:53:15 INFO - 'PAGER': '/bin/cat', 22:53:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:53:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:53:15 INFO - 'PWD': '/builds/slave/test', 22:53:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:53:15 INFO - 'SHELL': '/bin/bash', 22:53:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bYy3CyWRFS/Listeners', 22:53:15 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 22:53:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:53:15 INFO - 'USER': 'cltbld', 22:53:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:53:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:53:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:53:15 INFO - 'XPC_FLAGS': '0x0', 22:53:15 INFO - 'XPC_SERVICE_NAME': '0', 22:53:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:53:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:53:16 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-mhyYy0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:53:16 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-ryag2k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:53:16 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-le_HdX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:53:16 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-79Uuhz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:53:16 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-enzy4t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:53:16 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-JukgSr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:53:16 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-roe_4y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:53:16 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-AdHVw0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:53:16 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-wQzudN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:53:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:53:16 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-4j3frW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:53:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:53:17 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-zAzVrU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:53:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:53:17 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-jX3X1C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:53:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:53:17 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-J2ZAxY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:53:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:53:17 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-__NZMt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:53:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:53:17 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-bW9hr2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:53:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:53:17 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-g2qcdI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:53:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:53:17 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-aTNXV4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:53:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:53:17 INFO - Running setup.py install for manifestparser 22:53:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:53:17 INFO - Running setup.py install for mozcrash 22:53:18 INFO - Running setup.py install for mozdebug 22:53:18 INFO - Running setup.py install for mozdevice 22:53:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:53:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:53:18 INFO - Running setup.py install for mozfile 22:53:18 INFO - Running setup.py install for mozhttpd 22:53:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:53:18 INFO - Running setup.py install for mozinfo 22:53:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:53:18 INFO - Running setup.py install for mozInstall 22:53:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:53:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:53:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:53:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:53:18 INFO - Running setup.py install for mozleak 22:53:19 INFO - Running setup.py install for mozlog 22:53:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:53:19 INFO - Running setup.py install for moznetwork 22:53:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:53:19 INFO - Running setup.py install for mozprocess 22:53:19 INFO - Running setup.py install for mozprofile 22:53:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:53:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:53:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:53:19 INFO - Running setup.py install for mozrunner 22:53:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:53:19 INFO - Running setup.py install for mozscreenshot 22:53:20 INFO - Running setup.py install for moztest 22:53:20 INFO - Running setup.py install for mozversion 22:53:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:53:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:53:20 INFO - Cleaning up... 22:53:20 INFO - Return code: 0 22:53:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:53:20 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')]} 22:53:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:53:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:20 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')]} 22:53:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:53:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:20 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 0x10ab8da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab9d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c2a6d2c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa2ce00>, '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 0x10a855db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa27b20>, '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.ue6CevGy7Z/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.bYy3CyWRFS/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 22:53:20 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 22:53:20 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 22:53:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ue6CevGy7Z/Render', 22:53:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:53:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:53:20 INFO - 'HOME': '/Users/cltbld', 22:53:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:53:20 INFO - 'LOGNAME': 'cltbld', 22:53:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:53:20 INFO - 'MOZ_NO_REMOTE': '1', 22:53:20 INFO - 'NO_EM_RESTART': '1', 22:53:20 INFO - 'PAGER': '/bin/cat', 22:53:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:53:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:53:20 INFO - 'PWD': '/builds/slave/test', 22:53:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:53:20 INFO - 'SHELL': '/bin/bash', 22:53:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bYy3CyWRFS/Listeners', 22:53:20 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 22:53:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:53:20 INFO - 'USER': 'cltbld', 22:53:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:53:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:53:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:53:20 INFO - 'XPC_FLAGS': '0x0', 22:53:20 INFO - 'XPC_SERVICE_NAME': '0', 22:53:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:53:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:53:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:53:20 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-X_N4Lz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:53:20 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 22:53:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:53:20 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-BLKejT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:53:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:53:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:53:20 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-eQmeQ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:53:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:53:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:53:20 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-04rMR_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:53:21 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)) 22:53:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:53:21 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-xSAoFM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:53:21 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)) 22:53:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:53:21 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-ZsxZD2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:53:21 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)) 22:53:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:53:21 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-LIQNbm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:53:21 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)) 22:53:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:53:21 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-D8MHWd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:53:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 22:53:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:53:21 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-po91ZD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:53:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 22:53:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:53:21 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-9xkjJN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:53:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:53:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:53:21 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-wdkOlc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:53:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 22:53:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:53:21 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-YoD0e_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:53:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 22:53:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:53:21 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-7tSjL4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:53:22 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)) 22:53:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:53:22 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-ir3P2u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:53:22 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)) 22:53:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:53:22 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-8EEua1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:53:22 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)) 22:53:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:53:22 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-ToyLfd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:53:22 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)) 22:53:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:53:22 INFO - Running setup.py (path:/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/pip-AaOEIR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:53:22 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)) 22:53:22 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)) 22:53:22 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)) 22:53:22 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)) 22:53:22 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)) 22:53:22 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)) 22:53:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:53: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 22:53: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 22:53: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 22:53: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 22:53: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 22:53: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 22:53:24 INFO - Downloading blessings-1.6.tar.gz 22:53:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:53:25 INFO - Installing collected packages: blessings 22:53:25 INFO - Running setup.py install for blessings 22:53:25 INFO - Successfully installed blessings 22:53:25 INFO - Cleaning up... 22:53:25 INFO - Return code: 0 22:53:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:53:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:53:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:53:25 INFO - Reading from file tmpfile_stdout 22:53:25 INFO - Current package versions: 22:53:25 INFO - blessings == 1.6 22:53:25 INFO - blobuploader == 1.2.4 22:53:25 INFO - docopt == 0.6.1 22:53:25 INFO - manifestparser == 1.1 22:53:25 INFO - mozInstall == 1.12 22:53:25 INFO - mozcrash == 0.17 22:53:25 INFO - mozdebug == 0.1 22:53:25 INFO - mozdevice == 0.48 22:53:25 INFO - mozfile == 1.2 22:53:25 INFO - mozhttpd == 0.7 22:53:25 INFO - mozinfo == 0.9 22:53:25 INFO - mozleak == 0.1 22:53:25 INFO - mozlog == 3.1 22:53:25 INFO - moznetwork == 0.27 22:53:25 INFO - mozprocess == 0.22 22:53:25 INFO - mozprofile == 0.28 22:53:25 INFO - mozrunner == 6.11 22:53:25 INFO - mozscreenshot == 0.1 22:53:25 INFO - mozsystemmonitor == 0.0 22:53:25 INFO - moztest == 0.7 22:53:25 INFO - mozversion == 1.4 22:53:25 INFO - psutil == 3.1.1 22:53:25 INFO - requests == 1.2.3 22:53:25 INFO - wsgiref == 0.1.2 22:53:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:53:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:53:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:53:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:53:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:53:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:25 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 0x10ab8da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab9d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c2a6d2c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa2ce00>, '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 0x10a855db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa27b20>, '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.ue6CevGy7Z/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.bYy3CyWRFS/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 22:53:25 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 22:53:25 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 22:53:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ue6CevGy7Z/Render', 22:53:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:53:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:53:25 INFO - 'HOME': '/Users/cltbld', 22:53:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:53:25 INFO - 'LOGNAME': 'cltbld', 22:53:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:53:25 INFO - 'MOZ_NO_REMOTE': '1', 22:53:25 INFO - 'NO_EM_RESTART': '1', 22:53:25 INFO - 'PAGER': '/bin/cat', 22:53:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:53:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:53:25 INFO - 'PWD': '/builds/slave/test', 22:53:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:53:25 INFO - 'SHELL': '/bin/bash', 22:53:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bYy3CyWRFS/Listeners', 22:53:25 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 22:53:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:53:25 INFO - 'USER': 'cltbld', 22:53:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:53:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:53:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:53:25 INFO - 'XPC_FLAGS': '0x0', 22:53:25 INFO - 'XPC_SERVICE_NAME': '0', 22:53:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:53:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:53:25 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)) 22:53:25 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)) 22:53:25 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)) 22:53:25 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)) 22:53:25 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)) 22:53:25 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)) 22:53:25 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)) 22:53:25 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)) 22:53:25 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)) 22:53:25 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)) 22:53:25 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)) 22:53:25 INFO - Cleaning up... 22:53:25 INFO - Return code: 0 22:53:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:53:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:53:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:53:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:53:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:53:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:25 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 0x10ab8da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab9d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c2a6d2c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa2ce00>, '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 0x10a855db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa27b20>, '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.ue6CevGy7Z/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.bYy3CyWRFS/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 22:53:25 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 22:53:25 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 22:53:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ue6CevGy7Z/Render', 22:53:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:53:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:53:25 INFO - 'HOME': '/Users/cltbld', 22:53:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:53:25 INFO - 'LOGNAME': 'cltbld', 22:53:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:53:25 INFO - 'MOZ_NO_REMOTE': '1', 22:53:25 INFO - 'NO_EM_RESTART': '1', 22:53:25 INFO - 'PAGER': '/bin/cat', 22:53:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:53:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:53:25 INFO - 'PWD': '/builds/slave/test', 22:53:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:53:25 INFO - 'SHELL': '/bin/bash', 22:53:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bYy3CyWRFS/Listeners', 22:53:25 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 22:53:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:53:25 INFO - 'USER': 'cltbld', 22:53:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:53:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:53:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:53:25 INFO - 'XPC_FLAGS': '0x0', 22:53:25 INFO - 'XPC_SERVICE_NAME': '0', 22:53:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:53:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:53:26 INFO - Downloading/unpacking jsonschema 22:53:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:53:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:53:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:53:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:53:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:53:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:53:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:53:28 INFO - Downloading/unpacking functools32 (from jsonschema) 22:53:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:53:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:53:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:53:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:53:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:53:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:53:28 INFO - Downloading functools32-3.2.3-2.tar.gz 22:53:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:53:28 INFO - warning: no files found matching '*.txt' 22:53:28 INFO - no previously-included directories found matching 'build' 22:53:28 INFO - no previously-included directories found matching 'dist' 22:53:28 INFO - no previously-included directories found matching '.git*' 22:53:28 INFO - Installing collected packages: jsonschema, functools32 22:53:29 INFO - Running setup.py install for functools32 22:53:29 INFO - warning: no files found matching '*.txt' 22:53:29 INFO - no previously-included directories found matching 'build' 22:53:29 INFO - no previously-included directories found matching 'dist' 22:53:29 INFO - no previously-included directories found matching '.git*' 22:53:29 INFO - Successfully installed jsonschema functools32 22:53:29 INFO - Cleaning up... 22:53:29 INFO - Return code: 0 22:53:29 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:53:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:53:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:53:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:53:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:53:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:53:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ab8da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ab9d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9c2a6d2c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa2ce00>, '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 0x10a855db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa27b20>, '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.ue6CevGy7Z/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.bYy3CyWRFS/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 22:53:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 22:53:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 22:53:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ue6CevGy7Z/Render', 22:53:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:53:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:53:29 INFO - 'HOME': '/Users/cltbld', 22:53:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:53:29 INFO - 'LOGNAME': 'cltbld', 22:53:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:53:29 INFO - 'MOZ_NO_REMOTE': '1', 22:53:29 INFO - 'NO_EM_RESTART': '1', 22:53:29 INFO - 'PAGER': '/bin/cat', 22:53:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:53:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:53:29 INFO - 'PWD': '/builds/slave/test', 22:53:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:53:29 INFO - 'SHELL': '/bin/bash', 22:53:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bYy3CyWRFS/Listeners', 22:53:29 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 22:53:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:53:29 INFO - 'USER': 'cltbld', 22:53:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:53:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:53:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:53:29 INFO - 'XPC_FLAGS': '0x0', 22:53:29 INFO - 'XPC_SERVICE_NAME': '0', 22:53:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:53:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:53:29 INFO - Downloading/unpacking flake8 22:53:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:53:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:53:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:53:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:53:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:53:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:53:31 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:53:31 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:53:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:53:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:53:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:53:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:53:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:53:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:53:32 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:53:32 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:53:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:53:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:53:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:53:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:53:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:53:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:53:32 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:53:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:53:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:53:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:53:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:53:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:53:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:53:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:53:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:53:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:53:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:53:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:53:33 INFO - no previously-included directories found matching 'docs/_build' 22:53:33 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:53:33 INFO - Running setup.py install for pep8 22:53:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:53:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:53:33 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:53:33 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:53:33 INFO - no previously-included directories found matching 'docs/_build' 22:53:33 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:53:33 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:53:33 INFO - Cleaning up... 22:53:33 INFO - Return code: 0 22:53:33 INFO - Running post-action listener: _resource_record_post_action 22:53:33 INFO - Running post-action listener: _start_resource_monitoring 22:53:33 INFO - Starting resource monitoring. 22:53:33 INFO - ##### 22:53:33 INFO - ##### Running install step. 22:53:33 INFO - ##### 22:53:33 INFO - Running pre-action listener: _resource_record_pre_action 22:53:33 INFO - Running main action method: install 22:53:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:53:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:53:33 INFO - Reading from file tmpfile_stdout 22:53:33 INFO - Detecting whether we're running mozinstall >=1.0... 22:53:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:53:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:53:33 INFO - Reading from file tmpfile_stdout 22:53:33 INFO - Output received: 22:53:33 INFO - Usage: mozinstall [options] installer 22:53:33 INFO - Options: 22:53:33 INFO - -h, --help show this help message and exit 22:53:33 INFO - -d DEST, --destination=DEST 22:53:33 INFO - Directory to install application into. [default: 22:53:33 INFO - "/builds/slave/test"] 22:53:33 INFO - --app=APP Application being installed. [default: firefox] 22:53:33 INFO - mkdir: /builds/slave/test/build/application 22:53:33 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'] 22:53:33 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 22:54:07 INFO - Reading from file tmpfile_stdout 22:54:07 INFO - Output received: 22:54:07 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:54:07 INFO - Running post-action listener: _resource_record_post_action 22:54:07 INFO - ##### 22:54:07 INFO - ##### Running run-tests step. 22:54:07 INFO - ##### 22:54:07 INFO - Running pre-action listener: _resource_record_pre_action 22:54:07 INFO - Running main action method: run_tests 22:54:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:54:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:54:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:54:07 INFO - 2016-04-14 22:54:07.202 screenresolution[1649:8031] starting screenresolution argv=screenresolution get 22:54:07 INFO - 2016-04-14 22:54:07.205 screenresolution[1649:8031] Display 0: 1600x1200x32@60 22:54:07 INFO - 2016-04-14 22:54:07.210 screenresolution[1650:8033] starting screenresolution argv=screenresolution list 22:54:07 INFO - Available Modes on Display 0 22:54:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:54:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:54:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:54:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:54:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:54:07 INFO - Intel Iris: 22:54:07 INFO - Chipset Model: Intel Iris 22:54:07 INFO - Type: GPU 22:54:07 INFO - Bus: Built-In 22:54:07 INFO - VRAM (Dynamic, Max): 1536 MB 22:54:07 INFO - Vendor: Intel (0x8086) 22:54:07 INFO - Device ID: 0x0a2e 22:54:07 INFO - Revision ID: 0x0009 22:54:07 INFO - Displays: 22:54:07 INFO - Display: 22:54:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:54:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:54:07 INFO - Display Serial Number: 1600x1200 60 22:54:07 INFO - Main Display: Yes 22:54:07 INFO - Mirror: Off 22:54:07 INFO - Online: Yes 22:54:07 INFO - Rotation: Supported 22:54:07 INFO - Return code: 0 22:54:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:54:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:54:07 INFO - Python 2.7.3 22:54:07 INFO - Return code: 0 22:54:07 INFO - grabbing minidump binary from tooltool 22:54:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:54:07 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 0x10aa2ce00>, '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 0x10a855db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa27b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:54:07 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 22:54:07 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 22:54:07 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:54:07 INFO - Return code: 0 22:54:07 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:54:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:54:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:54:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:54:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:54:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:54:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:54:08 INFO - Return code: 0 22:54:08 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', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VP6EYMVjQjed8ZKaDJkvEQ/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_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 22:54:08 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 --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VP6EYMVjQjed8ZKaDJkvEQ/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_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 22:54:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ue6CevGy7Z/Render', 22:54:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:54:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:54:08 INFO - 'HOME': '/Users/cltbld', 22:54:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:54:08 INFO - 'LOGNAME': 'cltbld', 22:54:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:54:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:54:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:54:08 INFO - 'MOZ_NO_REMOTE': '1', 22:54:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:54:08 INFO - 'NO_EM_RESTART': '1', 22:54:08 INFO - 'PAGER': '/bin/cat', 22:54:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:54:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:54:08 INFO - 'PWD': '/builds/slave/test', 22:54:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:54:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:54:08 INFO - 'SHELL': '/bin/bash', 22:54:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bYy3CyWRFS/Listeners', 22:54:08 INFO - 'TMPDIR': '/var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/', 22:54:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:54:08 INFO - 'USER': 'cltbld', 22:54:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:54:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:54:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:54:08 INFO - 'XPC_FLAGS': '0x0', 22:54:08 INFO - 'XPC_SERVICE_NAME': '0', 22:54:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:54:08 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VP6EYMVjQjed8ZKaDJkvEQ/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_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 22:54:08 INFO - mozversion application_buildid: 20160414215439 22:54:08 INFO - mozversion application_changeset: 7b9fbf34a1d6c4a4a54486b64f2be9c8b304401d 22:54:08 INFO - mozversion application_display_name: Nightly 22:54:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:54:08 INFO - mozversion application_name: Firefox 22:54:08 INFO - mozversion application_remotingname: firefox 22:54:08 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 22:54:08 INFO - mozversion application_vendor: Mozilla 22:54:08 INFO - mozversion application_version: 48.0a1 22:54:08 INFO - mozversion platform_buildid: 20160414215439 22:54:08 INFO - mozversion platform_changeset: 7b9fbf34a1d6c4a4a54486b64f2be9c8b304401d 22:54:08 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 22:54:08 INFO - mozversion platform_version: 48.0a1 22:54:08 INFO - using testdate: 1460699648 22:54:08 INFO - actual date: 1460699648 22:54:08 INFO - starting webserver on 'localhost:49275' 22:54:08 INFO - SUITE-START | Running 2 tests 22:54:08 INFO - TEST-START | dromaeo_css 22:54:08 INFO - operating with platform_type : mac_ 22:54:08 INFO - Initialising browser for dromaeo_css test... 22:54:08 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/tmpqLqhYR/profile http://localhost:49275/getInfo.html) 22:54:11 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 22:54:11 INFO - PROCESS | 1668 | colorDepth:24 22:54:11 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 22:54:11 INFO - PROCESS | 1668 | __metrics 22:54:11 INFO - PROCESS | 1668 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:54:11 ERROR - PROCESS | 1668 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:54:11 INFO - TEST-INFO | 1668: exit 0 22:54:11 INFO - Browser initialized. 22:54:11 INFO - Running cycle 1/1 for dromaeo_css test... 22:54:11 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/tmpqLqhYR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:54:23 INFO - PROCESS | 1671 | RSS: Main: 237715456 22:54:23 INFO - PROCESS | 1671 | 22:55:15 INFO - PROCESS | 1671 | 1460699715529 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] 22:57:25 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 22:57:27 INFO - PROCESS | 1671 | RSS: Main: 366714880 22:57:27 INFO - PROCESS | 1671 | 23:00:30 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 23:00:30 INFO - PROCESS | 1671 | RSS: Main: 370065408 23:00:30 INFO - PROCESS | 1671 | 23:03:33 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 23:03:33 INFO - PROCESS | 1671 | RSS: Main: 377446400 23:03:33 INFO - PROCESS | 1671 | 23:06:35 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 23:06:36 INFO - PROCESS | 1671 | RSS: Main: 371519488 23:06:36 INFO - PROCESS | 1671 | 23:09:39 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 23:09:40 INFO - PROCESS | 1671 | RSS: Main: 339103744 23:09:40 INFO - PROCESS | 1671 | 23:12:42 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 23:12:43 INFO - PROCESS | 1671 | RSS: Main: 384352256 23:12:43 INFO - PROCESS | 1671 | 23:12:43 INFO - PROCESS | 1671 | __start_tp_report 23:12:43 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 23:12:43 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 23:12:43 INFO - PROCESS | 1671 | |i|pagename|runs| 23:12:43 INFO - PROCESS | 1671 | |0;dojo.html;3395.9151021224466;3442.053435305291;3446.138465383654;3484.1463841358864;3514.2093029068446;5589;5678.886422271552;5686.0333743263645;5702.173184888194;5716.742746576404;5619.353774315955;5672.943270567293;5686.772529098841;5690.886182276352;5691.032524470839;5140.999999999991;5151.974240128794;5175.974120129394;5193.610479214055;5246.291750613656;5252;5256.08018596744;5260.368755749297;5265.86783841474;5267.499587539178;5141;5189.8183563575285;5205.16717325228;5211.86970325743;5312.256284120207;5211.270422140922;5224.242484839677;5225.085610018232;5233.738313084331;5301.363836339626;5081.390233172043;5103.9489605103845;5131.87170320745;5162.1224391853475;5164.147915593922;5500.037493438672;5514.200440936082;5543;5561.026820306471;5608.579356548275;6221.720022598994;6243.063540468966;6243.875122497525;6247.750089996394;6254.499640028786;6048.803751689035;6185;6233.2208473940755;6293.087502312185;6309.684515774193;5724.513416359656;5757.337906140764;5760.53915686744;5786.189933409242;5803.419658034205;5945.810837832364;6257.812265632038;6261.154744109578;6261.655608941461;6263.624182548971;5130.974345128325;5461.244310165202;5990.191324171268;6061.484773794277;6123;6245.594036387621;6247.500199983991;6250.1874756281395;6252;6252.249730032425;5430.67415955044;6124.112003759519;6200.162977998003;6201.10084037821;6210.254769427697;6063.939360606338;6112.473085128069;6123.221615589998;6125.47964319526;6168.347610527516;4831.806727730922;4859.757012149378;4921.50784921505;4922.58158056562;4954.207326827763;4397.164538737694;4399;4402.4056752338665;4458.375827384169;4464.352668862996;5631.577631677558;5664.943350566524;5665.631733937364;5665.886682266331;5669.40471250521;6117.296510901303;6170.660613666291;6171.506279497719;6181.103739957681;6271.341509141565;5608.85977850557;5613.298337707786;5618.719064046782;5631.971840140855;5649.802256920988;5330.387005494418;5330.680159190461;5334.333208348957;5345.33183352081;5398.082326004589;6116.761878222017;6234.906476402948;6238.563300568917;6275.3724627536885;6293.748250069946;6199.814005579839;6239.875202495834;6254.906176407449;6262.5929314594405;6277.435030847246;6295.307516173309;6298;6304.275008373912;6314.431701146918;6338.080978258219;6014.609050411709;6106.236720409949;6131.988406491147;6138.07928810682;6160.784372546939;6214.689265536615;6238.376162383725;6240.282367527792;6241.750329986749;6242.937570624235;5620.735334549857;5872.530197584098;6056;6060.515158787374;6080.148779170831;5936.37668044849;6038.245219347582;6040.486558642388;6084.8783024340155;6136.509079273558;5904.025835737055;5915.852103697442;5939.10913362999;5939.2575928009;5951.404859514014;5897.852553686193;6064.939350606437;6072.14989901423;6077.817665470043;6122.755089796537;5824.039033559585;5834.241548598655;5935.584509084322;5970.044792833126;5999.100134979788 23:12:43 INFO - PROCESS | 1671 | |1;ext.html;504.5811976059871;507.8121095194779;509.35312153564973;572.6335145506876;603.2067830802497;29714;30337.241568960788;30359.848200758966;30384.08847734569;30400.847995760018;30766.692333076717;31275.90534331299;39744.40383394252;40909.590904090954;41395.37906931391;9400.764980875481;9563.713088607354;9611.519424028806;9615.855762163537;9621;9612.90387096127;9718.708438746813;9731.172850307697;9737.415755054719;9739.26955478342;8503.702370417037;8783.472991620523;11517.19379643421;11860.288382697023;11904.047676185884;11424.371659558721;11521.884781152248;11538.59614913474;11543.249688770204;11548.769024619545;8410.873836892451;11280.548778048867;11319.773604527945;11467.713307167303;11474.188823738237;31868.769186156977;38827.805860970795;39361.60638393608;39469;39503;3118.98440507796;3264.151320656623;3279.3441311737747;3343.3981883261004;3459.238967427162;29009.41981160386;29190.854045729848;29515.262118446997;29902.551461728093;30002.699973000428;2722.659667541557;2731.6039174319812;2739.849308288043;2755.0908200293857;2922.254825019628;481967;482800;483991.9999999965;485988;487761;35258.64741352605;36231.913042608765;36252;36327.63672363295;36366.454503182475;35688.64311356905;35755.821220893726;35855.28289434249;36011.09972250715;36013.45979810305;15010.24948752558;15143.318550665246;15235;15251.542453726406;15257.6185595361;12823.551175708992;12864.485420583072;12877.613671589866;13167.736645267041;13193.076484645982;6953.443724502028;7612.8477430451085;7765.92234077652;8111.716089936824;8250.216229458192;3322.700956913841;3354.8490317935753;3373.392789297901;3397.1676939150066;3404.540387047766;934.8831396075491;1000.8698869146964;1078.191356482638;1078.7357097511003;1137.9487923043482;28143.71856281452;28654.99707510227;30114.54828177579;30604;30702.84648576743;976;1057.4078119104747;1058.9682309530726;1088.3633074651418;1094.1356328500556;15382.230888455757;15639.609009774847;15691.1369874658;15703.68592628141;16007.318316786972;623405;687261;701777.4911125412;702093;703559;53942.33605471369;55121.44878551243;62402.37597623966;63042.36957630457;64017.67991160014;1491.2990894279887;1497.2887878258175;1500.7598784194695;1506.4652048522835;1578.4396539228637;24572.6314105285;28945.131646050653;29286;30336.089917302517;30992.91524796621;48387.5161248383;58265;60213.39786602078;60216;60297.397026029175;446449;447038;675543;692133.5393323001;814380;42698.57301426946;42780;43089.56910430855;43524;43526.782366087966;47419.68498040516;47700.80747981328;47867;47910;49526.00947981092;39059.41410878896;39177.6082239174;39565.40651890282;40120.59879401168;40792.38811417772;38155.42766858444;38389;38796;39705.60294397019;39999.00002499961;37908.62091379051;38680.41979370368;38791;38944.2211155781;39206.215875682894;28053.859730701217;32298.83850580732;33678.66321336755;33930.96414215155;34030;24660.753392466562;24897.875510623056;25081.372965676004;25505.48989020246;25513.362165946 23:12:43 INFO - PROCESS | 1671 | |2;jquery.html;331.97585448890175;354.7339495378466;386.11772100749795;395.9903489789318;403.14332044405637;19807.801921980776;19982.60034799305;20720.689189662167;21246.89376553115;21284.787152128472;11592.362420066893;11637.825432618496;12252.632421027361;12263.386830658475;12264.018878489722;5215.973920130404;5406.459354064601;5442.183672449125;5442.6462279951875;5443.129099344106;3851.5378154621485;4129.4012368206595;5727.083666613343;6074.787382441616;6085.178500902366;6048.969755151218;6066.120412540179;6073.240844894388;6082.604630699013;6231.968840155793;6243.282022567418;6407.679616019181;6419.64691941943;6423.582467139621;6434.292227854956;3499.5800503939436;3654.5797233318244;5356.982173387044;5741.425857414267;5753.108268218432;13142.934285328607;13417.12788668743;13464.663383415394;13530.18818870871;13600.04799664024;2057.1668474267944;2178.357384571555;2181.1384503121335;2182.5743979923923;2239.865608063505;9954.104130628206;10264.435456049914;10308.072273495422;10545.314554554006;14559.417623295056;1672.4146548707854;1700.8604235162347;1707.1890851845274;1742.033171589767;1774.9290028398848;99832.40725038154;106182;107538;107720;108030.45984770026;9097.999220085785;11562.88437115635;11566;11570.94214528922;11651.650450486499;15018.399264029316;15031.323590438456;15049.699006019819;15070.773938390932;15077.472288469851;7113.92886071143;8665.220130188314;8723.563821808884;8754;8775.824483510294;8647.048824629286;8684.348673849487;8710.21608055278;8801.119888011148;8853.955730221307;4496.123255965055;4553.704009239389;4554.726716396961;4563.863084107482;4622.14490319292;1501.3469140923733;1668.3159904439121;1840.2734462712847;1852.536865783554;1964.7838737738837;822.942394032424;934.8737920380797;935.5322338830584;936.9109934556207;949.6960972488876;19464;20799.8960005199;20853;20878.895605521877;20956.79043209579;963.2149797914677;965.8406362950175;984.9064338887795;992.1071036067597;1025.661531694539;10534.36793792375;10890.401027943539;10909.67270981856;10935.17986151026;10975.890241097486;248026;354410;592371;595866;603030.9393812099;116732;126539;126870.36564817117;130947.69052309546;132354;1014.6499457687086;1029.315505189041;1078.3853203673987;1102.0522350778324;1104.320842681745;15736.741060715345;15857.365705372118;16694;17804.55488612795;17808.643827123127;47122.58632241038;47263.76368118137;47570.286445704034;48680;50039;59292.70353648204;70731.64634176796;74232.25767742253;75243.62378188292;76749.61625192061;6819.215790184192;7219.0976127984;7310.086239220098;7351.632418379167;7563.373874616984;11237.157213209206;11726.70683232926;11967.581134660246;12004.939975300067;12322.445489952794;8467.61895714682;10292.639757608447;10392.688219353431;10458.63394781179;10511.842322365017;10112.59549618007;10154.847677284994;10438.530266138194;10579.598337605332;10602.89397106019;10867.891321087005;11061.612843550438;11212.719682008015;11284.21010529288;11326.490307935997;7267.164276108315;7350.338469537767;7417.1841097478155;7420.295071968154;7637.770866874002;10312.845307320245;10472.68581942543;10995.175361847734;11037.668869933916;11064.944675276894 23:12:43 INFO - PROCESS | 1671 | |3;mootools.html;1391.5129704603385;1568.4040064775384;1581.1382796375974;1812.6737187306287;1946.5328321202908;7791.805204869881;8444.028936672285;8540.402171847973;8612.00961889383;8626.051134375213;8291.751247462586;8488.91511084889;8493.193146651058;8497.447665901727;8507.234348908598;2362.704661917256;2390.3904504351367;2394.293683363412;2394.6408038794234;2396.0775101585928;2364.4916342986253;2385.296337580418;2412.3124909400826;2417.3714834143075;2433.9634905476432;1973.733545971297;2385.355953892448;2396.4727759892794;2419.3709635494724;2421.2131057406414;2368.289513146049;2386.809055275574;2396.676448679423;2397.2089210560475;2414.420539070628;2307.261676263596;2378.77401646845;2387.4866903615807;2389.808815294772;2390.9043638254448;7667.233276672344;7907.762767116938;8566.70016549424;8630.352723545697;8664.826703465958;3798.5061941947647;4878.951210487885;4887.8778030549165;5106.668066575698;5259.317018554064;2835.7873159512915;3360.865565377382;3408.2672225471642;3456.5679290088738;3471.2710330830555;1002.6190047781869;1025.0159846547324;1025.9689012542422;1029.4543891737392;1029.5469993202967;10592.576296948113;10876.293040952392;11080.224384293102;11180.832287515694;11189.048930840889;4427.247367947457;4559.270516717309;4690.179218636725;4800.207965685623;4808.543188397097;8006.719764808204;9644.987276336024;9693.563789629343;9698.030196980386;9698.272629552812;5264.842054738364;5269.235960785664;5283.128283833124;5291.915157392726;5294.152935530374;4786.210275304536;4816.518348165226;4848.75756212195;4853.0779151961015;4889.706617602955;3904.824282907276;3929.31237033518;3997.4403583498333;4032.294348489003;4057.9391309130388;1315.7828958221978;1328.110166188656;1328.5482935801874;1334.8598397168353;1339.6918708697053;774.9380049596019;843.6667516331138;853.3514528958036;888.05865782271;968.070652173921;5533.861653458695;5695.572832037614;5887.61730487517;5901.911471327933;5903.704814759245;428.88634511854383;433.22669035770997;437.63676148796446;445.5544455544456;445.9531749166356;3709.8145092745253;3740.2706472238197;3801.657850793442;3817.656410922975;3821.426785982125;10039.548220330104;10476.109530689824;10530.262881598215;10570.365778053341;10579.153667706565;10177.84733229002;10456.163506919429;10538.631147909786;10568.313059651098;10705;6737.225219099768;7854.1753115922;8054;8078;8159.918400815915;5556.138798486241;5624;5663.57523185771;5676.4323567642905;5841.50347220491;9628.229741620513;9710.75723106928;9796;9871.605135794487;9902.455364954996;9072.818543629222;9321.860172097287;9420.434773913586;9720.222382209266;9966.053224943618;5787.305523337226;6689.866202676017;6794.52438329332;7310.780676579711;7313.926860731324;7747.496412733154;7933.9603301983125;8019.518828870287;8031.076426210827;8043.919560804317;7181.928180718335;7195.136583609791;7357.0803649543805;7381.44639152055;7398.371138453292;6156.507479401547;7135.143782745896;7166.820829479097;7240.203577606564;7243.674034668558;6790.5925644461495;6798.320167983162;6878.896816547855;6879.449644028366;6882.311768823278;4128.8348466061025;4688.882777930579;4720.433547974265;4843.24929635907;4860.222364421676;4721.811127554859;4907.3620429343955;4929.334539837219;4932.383452068491;4941.838667913109 23:12:43 INFO - PROCESS | 1671 | |4;prototype.html;310.63655523038045;316.476231836311;319.2131396108592;325.0557131532898;329.1589987581728;5247.580193584514;5762.847430513903;5809.186713860063;5848.152017957394;5903.822885313437;4819.469858315591;5876.618019828718;5878.118282257664;5911.379305172959;5945.49215962582;3830.7480856077023;4195.496540415147;4344.761038142901;4344.913101737963;4365.519792822787;3996.220736956295;4071.674266058723;4175.352820312856;4296.935545966813;4392.670549708769;3998.660113890323;4016.533965102731;4264.445622069142;4367.235733746597;4370.519242883281;4193.5387107418155;4195;4389.6049355558;4415.779211039451;4415.823367065345;2976.9851150744325;3377.273886114497;3796.49431375291;3797.708779015148;3809.580946095927;5039.52124548171;5597.328320601513;5598.10430331145;5599.272094627713;5618.550515958714;4851.587615052689;5061.924071138899;5065.113605119126;5080.1617733074;5134.64057515974;4905.779239934212;6183.690815459209;6189.412005859436;6218.875622487525;6238.189035425366;3566.7503274770775;4257.467816522934;4259.188026716071;4262.701610887239;4268.975445892995;6237.469815065725;6546.869062618721;6547.377999090078;6555.836104097388;6556.213254409454;3806.314863324573;4294.463192100988;4321.114171594815;4328.156009578165;4330.41539392178;3924.6271604197555;4223.732880135947;4413.3159360299205;4414.315781053878;4462.286034234507;4702.224133018014;5017.874553136127;5080.034793389244;5096.789022915614;5110.156824124052;5522.392536820947;6249.7187626556915;6268.49852011838;6276.426665067301;6320.525960552977;1455.5851582299206;1481.9481318153812;1483.406637345049;1486.3757221966584;1488.7171437426855;815.0219736316444;931.8072866730596;961.4375590279625;967.7822489939707;986.2208855004609;5350.678959262456;5739.16782066598;5761.567882408836;5776.08307804556;5791.276090488689;5023.372078490189;5396.9730151348995;6459.160309159779;6462.870742585122;6493.701259747975;5611.579131565149;5770.4806567408295;5772.163036359704;5816.011278082736;5834.795782147605;3380.814055226937;3383.915402114967;3404.0766966663755;3409.488576713513;3416.914577135542;5760.855978600484;6279.937200628026;6778.204635037071;6778.932210677929;6800.285969973181;5783.797567085132;6555.311692272338;6737.797866064026;6747.730090796412;6756.729730810713;5914.6746928918465;6441.937080381778;6663.766768163188;6851.246362899978;6893.620850853151;4864.92702609454;6294.559380843297;6469.838254043499;6533.738650453929;6537.444317232898;5875.500582450369;5943.702814859326;6370.1718776558755;6416;6422.935770642233;5394.921015796778;5620.3536593290655;5764.818212266391;5941.970290148694;6497.935020649733;4014.0366312084307;4202.117555313296;4220.535741068541;4223.831046758095;4225.683073769541;5887.470127688529;6446.51887980504;6449.258335291502;6457.483401327976;6474.093626892145;5461.371942226536;6100.603460775035;6170.629762214102;6274.686265686606;6290.955439482095;6017.247844019497;6326.715297811517;6342.365763423621;6344.206974128234;6349.269833969152;6156.722947467464;6165.691715414301;6193.84515387119;6210.944139496206;6238.656873871981;5471.15499940021;5628.268325117709;5755.280589926259;5879.059350503899;5947.434993675594;5358.598105142052;5922.763089476545;6032.819015429544;6160;6170.845728856814 23:12:43 INFO - PROCESS | 1671 | |5;yui.html;622.411820829316;657.8026592022393;691.1740470138185;769.146247665092;810.3719617296598;14872.47946321879;14949.701005979887;15334.46329378472;15359.769603455958;15710.293036813342;14303.713925721479;14398.856011439882;14422.855771442282;14443.844492440607;14450.277486125678;727.0729819480152;911.4258017448998;915.7115508614799;921.3734660430904;921.741912264567;747.6112421540796;905.2531661379388;939.7133874168376;941.7598512379368;942.1708896171359;973.9967833131885;979.9559019844088;981.1856159387727;981.7643765496265;981.7741919358551;762.9661808249797;943.4575119306427;970.2868391732071;971.9805603887918;974.1768205866067;729.0595132279353;729.956202627844;731.6926890705917;731.7219456606508;732.6886073418775;14937.551873443705;15032.924835375754;15216.923915380463;15313.540593782205;15320.310586023656;12866.935665321707;12876.742465150739;13050.673733156747;13105.344732763324;13232.073754837165;9995.360971302889;10380.532876020521;10424.426656533888;10742.3017503862;10878;3516.208853008078;3556.039869235292;3560.394732895414;3601.549806274216;3674.3386190485703;88653.11346886514;92689.07310926872;92797.53601231951;92869.53565232198;93204;9588.616455341848;9598.184154346958;9649.324547281763;9888.604455821831;10021.198304135653;20752.58494830125;21373.824439655968;21384.893075534525;21403.929803510073;22525.21161759363;7463.328300452985;7479.289467500579;7597.012388389474;7784.844303113906;7922.801929951797;3488.2500262443696;4059.5331536873045;4072.226277007359;4074.4499492568716;4171.687123465692;3454.360943225511;3555.6444355564236;3565.3404120237838;3567.3578755823683;3631.7276204284785;630.1618846933567;637.3117033603697;644.2880616918331;644.713102669317;662.2384258103219;460.82028009076487;482.97826597803186;537.3659082282866;570.4865620941187;590.1354515634616;6308.432241098323;7567.9243207567215;7580.128285247157;7677.961610191914;8067.838643227103;475.6266330930203;482.39700374531833;532.2947095099009;569.7379205565403;579.6580017789524;3641.5812181598926;3717.182219911615;3748.8125593720206;3797.6202379762353;3976.1252524444576;70714.9392759109;84478;89231;89344;89457;55871.16193257104;75860.62069689727;76851.8472222917;77415;77584;68119.65940170268;69689.65155174192;69808;70060.59878802396;70616;7261.841099121899;7367.742129025459;7443.218462061622;7688.192739762469;7735.651895664596;29109;39353;42051;42913.57086429096;43300.133997319244;46371.536284638074;52363.95272094613;53945.730271348395;55379;56147.15779263232;8796.736097917073;10218.948905255425;10522.789544209227;10625.893741062491;10788.136949044214;11589.710257243636;11608.535658573563;11676.532938682698;11766.411679416165;11832.82250766222;10960.835587466354;11042.668719938414;11068.059214966495;11157.49791259379;11463.541458341573;10848.783024339627;10852.945735271272;11134.55461781552;11208.71978200519;11218.158638102339;8814.64262069861;10648.201384896318;10695.77813951396;10932;11016.889831101586;9084;9319.712932051061;9521.476318802255;9543.42739435636;9752.9024709752;9138.63445462174;9341.392809467363;9366.484843333683;9420;9616.711498655051 23:12:43 INFO - PROCESS | 1671 | __end_tp_report 23:12:43 INFO - PROCESS | 1671 | __start_cc_report 23:12:43 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,1243 23:12:43 INFO - PROCESS | 1671 | __end_cc_report 23:12:43 INFO - PROCESS | 1671 | __startTimestamp1460700763237__endTimestamp 23:12:43 INFO - PROCESS | 1671 | 23:12:43 INFO - PROCESS | 1671 | ------- Summary: start ------- 23:12:43 INFO - PROCESS | 1671 | Number of tests: 6 23:12:43 INFO - PROCESS | 1671 | 23:12:43 INFO - PROCESS | 1671 | [#0] dojo.html Cycles:165 Average:5699.76 Median:5897.85 stddev:616.90 (10.5%) stddev-sans-first:591.73 23:12:43 INFO - PROCESS | 1671 | Values: 3395.9 3442.1 3446.1 3484.1 3514.2 5589.0 5678.9 5686.0 5702.2 5716.7 5619.4 5672.9 5686.8 5690.9 5691.0 5141.0 5152.0 5176.0 5193.6 5246.3 5252.0 5256.1 5260.4 5265.9 5267.5 5141.0 5189.8 5205.2 5211.9 5312.3 5211.3 5224.2 5225.1 5233.7 5301.4 5081.4 5103.9 5131.9 5162.1 5164.1 5500.0 5514.2 5543.0 5561.0 5608.6 6221.7 6243.1 6243.9 6247.8 6254.5 6048.8 6185.0 6233.2 6293.1 6309.7 5724.5 5757.3 5760.5 5786.2 5803.4 5945.8 6257.8 6261.2 6261.7 6263.6 5131.0 5461.2 5990.2 6061.5 6123.0 6245.6 6247.5 6250.2 6252.0 6252.2 5430.7 6124.1 6200.2 6201.1 6210.3 6063.9 6112.5 6123.2 6125.5 6168.3 4831.8 4859.8 4921.5 4922.6 4954.2 4397.2 4399.0 4402.4 4458.4 4464.4 5631.6 5664.9 5665.6 5665.9 5669.4 6117.3 6170.7 6171.5 6181.1 6271.3 5608.9 5613.3 5618.7 5632.0 5649.8 5330.4 5330.7 5334.3 5345.3 5398.1 6116.8 6234.9 6238.6 6275.4 6293.7 6199.8 6239.9 6254.9 6262.6 6277.4 6295.3 6298.0 6304.3 6314.4 6338.1 6014.6 6106.2 6132.0 6138.1 6160.8 6214.7 6238.4 6240.3 6241.8 6242.9 5620.7 5872.5 6056.0 6060.5 6080.1 5936.4 6038.2 6040.5 6084.9 6136.5 5904.0 5915.9 5939.1 5939.3 5951.4 5897.9 6064.9 6072.1 6077.8 6122.8 5824.0 5834.2 5935.6 5970.0 5999.1 23:12:43 INFO - PROCESS | 1671 | 23:12:43 INFO - PROCESS | 1671 | [#1] ext.html Cycles:180 Average:70796.94 Median:35805.55 stddev:162874.27 (454.9%) stddev-sans-first:163245.66 23:12:43 INFO - PROCESS | 1671 | Values: 504.6 507.8 509.4 572.6 603.2 29714.0 30337.2 30359.8 30384.1 30400.8 30766.7 31275.9 39744.4 40909.6 41395.4 9400.8 9563.7 9611.5 9615.9 9621.0 9612.9 9718.7 9731.2 9737.4 9739.3 8503.7 8783.5 11517.2 11860.3 11904.0 11424.4 11521.9 11538.6 11543.2 11548.8 8410.9 11280.5 11319.8 11467.7 11474.2 31868.8 38827.8 39361.6 39469.0 39503.0 3119.0 3264.2 3279.3 3343.4 3459.2 29009.4 29190.9 29515.3 29902.6 30002.7 2722.7 2731.6 2739.8 2755.1 2922.3 481967.0 482800.0 483992.0 485988.0 487761.0 35258.6 36231.9 36252.0 36327.6 36366.5 35688.6 35755.8 35855.3 36011.1 36013.5 15010.2 15143.3 15235.0 15251.5 15257.6 12823.6 12864.5 12877.6 13167.7 13193.1 6953.4 7612.8 7765.9 8111.7 8250.2 3322.7 3354.8 3373.4 3397.2 3404.5 934.9 1000.9 1078.2 1078.7 1137.9 28143.7 28655.0 30114.5 30604.0 30702.8 976.0 1057.4 1059.0 1088.4 1094.1 15382.2 15639.6 15691.1 15703.7 16007.3 623405.0 687261.0 701777.5 702093.0 703559.0 53942.3 55121.4 62402.4 63042.4 64017.7 1491.3 1497.3 1500.8 1506.5 1578.4 24572.6 28945.1 29286.0 30336.1 30992.9 48387.5 58265.0 60213.4 60216.0 60297.4 446449.0 447038.0 675543.0 692133.5 814380.0 42698.6 42780.0 43089.6 43524.0 43526.8 47419.7 47700.8 47867.0 47910.0 49526.0 39059.4 39177.6 39565.4 40120.6 40792.4 38155.4 38389.0 38796.0 39705.6 39999.0 37908.6 38680.4 38791.0 38944.2 39206.2 28053.9 32298.8 33678.7 33931.0 34030.0 24660.8 24897.9 25081.4 25505.5 25513.4 23:12:43 INFO - PROCESS | 1671 | 23:12:43 INFO - PROCESS | 1671 | [#2] jquery.html Cycles:180 Average:30266.81 Median:21101.84 stddev:84593.56 (400.9%) stddev-sans-first:84801.01 23:12:43 INFO - PROCESS | 1671 | Values: 332.0 354.7 386.1 396.0 403.1 19807.8 19982.6 20720.7 21246.9 21284.8 11592.4 11637.8 12252.6 12263.4 12264.0 5216.0 5406.5 5442.2 5442.6 5443.1 3851.5 4129.4 5727.1 6074.8 6085.2 6049.0 6066.1 6073.2 6082.6 6232.0 6243.3 6407.7 6419.6 6423.6 6434.3 3499.6 3654.6 5357.0 5741.4 5753.1 13142.9 13417.1 13464.7 13530.2 13600.0 2057.2 2178.4 2181.1 2182.6 2239.9 9954.1 10264.4 10308.1 10545.3 14559.4 1672.4 1700.9 1707.2 1742.0 1774.9 99832.4 106182.0 107538.0 107720.0 108030.5 9098.0 11562.9 11566.0 11570.9 11651.7 15018.4 15031.3 15049.7 15070.8 15077.5 7113.9 8665.2 8723.6 8754.0 8775.8 8647.0 8684.3 8710.2 8801.1 8854.0 4496.1 4553.7 4554.7 4563.9 4622.1 1501.3 1668.3 1840.3 1852.5 1964.8 822.9 934.9 935.5 936.9 949.7 19464.0 20799.9 20853.0 20878.9 20956.8 963.2 965.8 984.9 992.1 1025.7 10534.4 10890.4 10909.7 10935.2 10975.9 248026.0 354410.0 592371.0 595866.0 603030.9 116732.0 126539.0 126870.4 130947.7 132354.0 1014.6 1029.3 1078.4 1102.1 1104.3 15736.7 15857.4 16694.0 17804.6 17808.6 47122.6 47263.8 47570.3 48680.0 50039.0 59292.7 70731.6 74232.3 75243.6 76749.6 6819.2 7219.1 7310.1 7351.6 7563.4 11237.2 11726.7 11967.6 12004.9 12322.4 8467.6 10292.6 10392.7 10458.6 10511.8 10112.6 10154.8 10438.5 10579.6 10602.9 10867.9 11061.6 11212.7 11284.2 11326.5 7267.2 7350.3 7417.2 7420.3 7637.8 10312.8 10472.7 10995.2 11037.7 11064.9 23:12:43 INFO - PROCESS | 1671 | 23:12:43 INFO - PROCESS | 1671 | [#3] mootools.html Cycles:180 Average:5504.15 Median:4869.59 stddev:3067.96 (63.0%) stddev-sans-first:3061.00 23:12:43 INFO - PROCESS | 1671 | Values: 1391.5 1568.4 1581.1 1812.7 1946.5 7791.8 8444.0 8540.4 8612.0 8626.1 8291.8 8488.9 8493.2 8497.4 8507.2 2362.7 2390.4 2394.3 2394.6 2396.1 2364.5 2385.3 2412.3 2417.4 2434.0 1973.7 2385.4 2396.5 2419.4 2421.2 2368.3 2386.8 2396.7 2397.2 2414.4 2307.3 2378.8 2387.5 2389.8 2390.9 7667.2 7907.8 8566.7 8630.4 8664.8 3798.5 4879.0 4887.9 5106.7 5259.3 2835.8 3360.9 3408.3 3456.6 3471.3 1002.6 1025.0 1026.0 1029.5 1029.5 10592.6 10876.3 11080.2 11180.8 11189.0 4427.2 4559.3 4690.2 4800.2 4808.5 8006.7 9645.0 9693.6 9698.0 9698.3 5264.8 5269.2 5283.1 5291.9 5294.2 4786.2 4816.5 4848.8 4853.1 4889.7 3904.8 3929.3 3997.4 4032.3 4057.9 1315.8 1328.1 1328.5 1334.9 1339.7 774.9 843.7 853.4 888.1 968.1 5533.9 5695.6 5887.6 5901.9 5903.7 428.9 433.2 437.6 445.6 446.0 3709.8 3740.3 3801.7 3817.7 3821.4 10039.5 10476.1 10530.3 10570.4 10579.2 10177.8 10456.2 10538.6 10568.3 10705.0 6737.2 7854.2 8054.0 8078.0 8159.9 5556.1 5624.0 5663.6 5676.4 5841.5 9628.2 9710.8 9796.0 9871.6 9902.5 9072.8 9321.9 9420.4 9720.2 9966.1 5787.3 6689.9 6794.5 7310.8 7313.9 7747.5 7934.0 8019.5 8031.1 8043.9 7181.9 7195.1 7357.1 7381.4 7398.4 6156.5 7135.1 7166.8 7240.2 7243.7 6790.6 6798.3 6878.9 6879.4 6882.3 4128.8 4688.9 4720.4 4843.2 4860.2 4721.8 4907.4 4929.3 4932.4 4941.8 23:12:43 INFO - PROCESS | 1671 | 23:12:43 INFO - PROCESS | 1671 | [#4] prototype.html Cycles:180 Average:5016.58 Median:5747.22 stddev:1573.34 (27.4%) stddev-sans-first:1537.59 23:12:43 INFO - PROCESS | 1671 | Values: 310.6 316.5 319.2 325.1 329.2 5247.6 5762.8 5809.2 5848.2 5903.8 4819.5 5876.6 5878.1 5911.4 5945.5 3830.7 4195.5 4344.8 4344.9 4365.5 3996.2 4071.7 4175.4 4296.9 4392.7 3998.7 4016.5 4264.4 4367.2 4370.5 4193.5 4195.0 4389.6 4415.8 4415.8 2977.0 3377.3 3796.5 3797.7 3809.6 5039.5 5597.3 5598.1 5599.3 5618.6 4851.6 5061.9 5065.1 5080.2 5134.6 4905.8 6183.7 6189.4 6218.9 6238.2 3566.8 4257.5 4259.2 4262.7 4269.0 6237.5 6546.9 6547.4 6555.8 6556.2 3806.3 4294.5 4321.1 4328.2 4330.4 3924.6 4223.7 4413.3 4414.3 4462.3 4702.2 5017.9 5080.0 5096.8 5110.2 5522.4 6249.7 6268.5 6276.4 6320.5 1455.6 1481.9 1483.4 1486.4 1488.7 815.0 931.8 961.4 967.8 986.2 5350.7 5739.2 5761.6 5776.1 5791.3 5023.4 5397.0 6459.2 6462.9 6493.7 5611.6 5770.5 5772.2 5816.0 5834.8 3380.8 3383.9 3404.1 3409.5 3416.9 5760.9 6279.9 6778.2 6778.9 6800.3 5783.8 6555.3 6737.8 6747.7 6756.7 5914.7 6441.9 6663.8 6851.2 6893.6 4864.9 6294.6 6469.8 6533.7 6537.4 5875.5 5943.7 6370.2 6416.0 6422.9 5394.9 5620.4 5764.8 5942.0 6497.9 4014.0 4202.1 4220.5 4223.8 4225.7 5887.5 6446.5 6449.3 6457.5 6474.1 5461.4 6100.6 6170.6 6274.7 6291.0 6017.2 6326.7 6342.4 6344.2 6349.3 6156.7 6165.7 6193.8 6210.9 6238.7 5471.2 5628.3 5755.3 5879.1 5947.4 5358.6 5922.8 6032.8 6160.0 6170.8 23:12:43 INFO - PROCESS | 1671 | 23:12:43 INFO - PROCESS | 1671 | [#5] yui.html Cycles:180 Average:17456.27 Median:570.11 stddev:24691.65 (4331.0%) stddev-sans-first:24728.56 23:12:43 INFO - PROCESS | 1671 | Values: 622.4 657.8 691.2 769.1 810.4 14872.5 14949.7 15334.5 15359.8 15710.3 14303.7 14398.9 14422.9 14443.8 14450.3 727.1 911.4 915.7 921.4 921.7 747.6 905.3 939.7 941.8 942.2 974.0 980.0 981.2 981.8 981.8 763.0 943.5 970.3 972.0 974.2 729.1 730.0 731.7 731.7 732.7 14937.6 15032.9 15216.9 15313.5 15320.3 12866.9 12876.7 13050.7 13105.3 13232.1 9995.4 10380.5 10424.4 10742.3 10878.0 3516.2 3556.0 3560.4 3601.5 3674.3 88653.1 92689.1 92797.5 92869.5 93204.0 9588.6 9598.2 9649.3 9888.6 10021.2 20752.6 21373.8 21384.9 21403.9 22525.2 7463.3 7479.3 7597.0 7784.8 7922.8 3488.3 4059.5 4072.2 4074.4 4171.7 3454.4 3555.6 3565.3 3567.4 3631.7 630.2 637.3 644.3 644.7 662.2 460.8 483.0 537.4 570.5 590.1 6308.4 7567.9 7580.1 7678.0 8067.8 475.6 482.4 532.3 569.7 579.7 3641.6 3717.2 3748.8 3797.6 3976.1 70714.9 84478.0 89231.0 89344.0 89457.0 55871.2 75860.6 76851.8 77415.0 77584.0 68119.7 69689.7 69808.0 70060.6 70616.0 7261.8 7367.7 7443.2 7688.2 7735.7 29109.0 39353.0 42051.0 42913.6 43300.1 46371.5 52364.0 53945.7 55379.0 56147.2 8796.7 10218.9 10522.8 10625.9 10788.1 11589.7 11608.5 11676.5 11766.4 11832.8 10960.8 11042.7 11068.1 11157.5 11463.5 10848.8 10852.9 11134.6 11208.7 11218.2 8814.6 10648.2 10695.8 10932.0 11016.9 9084.0 9319.7 9521.5 9543.4 9752.9 9138.6 9341.4 9366.5 9420.0 9616.7 23:12:43 INFO - PROCESS | 1671 | -------- Summary: end -------- 23:12:43 INFO - PROCESS | 1671 | 23:12:43 INFO - TEST-INFO | 1671: exit 0 23:12:43 INFO - TEST-OK | dromaeo_css | took 1114836ms 23:12:43 INFO - TEST-START | kraken 23:12:43 INFO - operating with platform_type : mac_ 23:12:43 INFO - Initialising browser for kraken test... 23:12:43 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/tmpMn_nf9/profile http://localhost:49275/getInfo.html) 23:12:45 INFO - PROCESS | 1676 | __metrics Screen width/height:1600/1200 23:12:45 INFO - PROCESS | 1676 | colorDepth:24 23:12:45 INFO - PROCESS | 1676 | Browser inner width/height: 1024/689 23:12:46 INFO - PROCESS | 1676 | __metrics 23:12:46 INFO - PROCESS | 1676 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 23:12:46 ERROR - PROCESS | 1676 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:12:46 INFO - TEST-INFO | 1676: exit 0 23:12:46 INFO - Browser initialized. 23:12:46 INFO - Running cycle 1/1 for kraken test... 23:12:46 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/sn/mz3kt0bj0yzbmvjqtdrsjfz000000w/T/tmpMn_nf9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:12:58 INFO - PROCESS | 1678 | RSS: Main: 237731840 23:12:58 INFO - PROCESS | 1678 | 23:13:01 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 23:13:02 INFO - PROCESS | 1678 | RSS: Main: 286924800 23:13:02 INFO - PROCESS | 1678 | 23:13:05 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 23:13:05 INFO - PROCESS | 1678 | RSS: Main: 299515904 23:13:05 INFO - PROCESS | 1678 | 23:13:09 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 23:13:09 INFO - PROCESS | 1678 | RSS: Main: 386932736 23:13:09 INFO - PROCESS | 1678 | 23:13:11 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 23:13:12 INFO - PROCESS | 1678 | RSS: Main: 286363648 23:13:12 INFO - PROCESS | 1678 | 23:13:14 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:13:15 INFO - PROCESS | 1678 | RSS: Main: 290635776 23:13:15 INFO - PROCESS | 1678 | 23:13:18 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 23:13:18 INFO - PROCESS | 1678 | RSS: Main: 330723328 23:13:18 INFO - PROCESS | 1678 | 23:13:21 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 23:13:22 INFO - PROCESS | 1678 | RSS: Main: 330055680 23:13:22 INFO - PROCESS | 1678 | 23:13:24 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 23:13:25 INFO - PROCESS | 1678 | RSS: Main: 329154560 23:13:25 INFO - PROCESS | 1678 | 23:13:27 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:13:27 INFO - PROCESS | 1678 | RSS: Main: 319967232 23:13:27 INFO - PROCESS | 1678 | 23:13:29 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:13:30 INFO - PROCESS | 1678 | RSS: Main: 326758400 23:13:30 INFO - PROCESS | 1678 | 23:13:32 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:13:32 INFO - PROCESS | 1678 | RSS: Main: 329822208 23:13:32 INFO - PROCESS | 1678 | 23:13:35 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:13:36 INFO - PROCESS | 1678 | RSS: Main: 334663680 23:13:36 INFO - PROCESS | 1678 | 23:13:39 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:13:39 INFO - PROCESS | 1678 | RSS: Main: 332132352 23:13:39 INFO - PROCESS | 1678 | 23:13:41 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 23:13:41 INFO - PROCESS | 1678 | RSS: Main: 396066816 23:13:41 INFO - PROCESS | 1678 | 23:13:41 INFO - PROCESS | 1678 | __start_tp_report 23:13:41 INFO - PROCESS | 1678 | _x_x_mozilla_page_load 23:13:41 INFO - PROCESS | 1678 | _x_x_mozilla_page_load_details 23:13:41 INFO - PROCESS | 1678 | |i|pagename|runs| 23:13:41 INFO - PROCESS | 1678 | |0;ai-astar;156;162;118;111;116;112;119;112;113;111 23:13:41 INFO - PROCESS | 1678 | |1;audio-beat-detection;149;122;144;172;121;143;144;121;174;127 23:13:41 INFO - PROCESS | 1678 | |2;audio-dft;186;187;184;184;184;183;184;183;185;186 23:13:41 INFO - PROCESS | 1678 | |3;audio-fft;90;75;86;89;56;59;56;79;96;80 23:13:41 INFO - PROCESS | 1678 | |4;audio-oscillator;92;90;131;77;77;80;123;77;76;127 23:13:41 INFO - PROCESS | 1678 | |5;imaging-gaussian-blur;87;85;96;131;113;84;87;84;102;131 23:13:41 INFO - PROCESS | 1678 | |6;imaging-darkroom;94;94;94;94;93;94;94;94;93;93 23:13:41 INFO - PROCESS | 1678 | |7;imaging-desaturate;84;86;85;84;85;84;84;84;84;84 23:13:41 INFO - PROCESS | 1678 | |8;json-parse-financial;60;60;59;59;57;57;59;73;57;56 23:13:41 INFO - PROCESS | 1678 | |9;json-stringify-tinderbox;64;64;80;77;72;67;68;68;69;70 23:13:41 INFO - PROCESS | 1678 | |10;stanford-crypto-aes;84;86;85;85;85;84;85;85;87;86 23:13:41 INFO - PROCESS | 1678 | |11;stanford-crypto-ccm;145;152;162;144;141;138;138;182;198;155 23:13:41 INFO - PROCESS | 1678 | |12;stanford-crypto-pbkdf2;167;178;168;168;170;169;170;169;169;163 23:13:41 INFO - PROCESS | 1678 | |13;stanford-crypto-sha256-iterative;70;69;69;69;68;67;67;72;65;68 23:13:41 INFO - PROCESS | 1678 | __end_tp_report 23:13:41 INFO - PROCESS | 1678 | __start_cc_report 23:13:41 INFO - PROCESS | 1678 | _x_x_mozilla_cycle_collect,1899 23:13:41 INFO - PROCESS | 1678 | __end_cc_report 23:13:41 INFO - PROCESS | 1678 | __startTimestamp1460700821935__endTimestamp 23:13:41 INFO - PROCESS | 1678 | 23:13:41 INFO - PROCESS | 1678 | ------- Summary: start ------- 23:13:41 INFO - PROCESS | 1678 | Number of tests: 14 23:13:41 INFO - PROCESS | 1678 | 23:13:41 INFO - PROCESS | 1678 | [#0] ai-astar Cycles:10 Average:123.00 Median:117.00 stddev:19.24 (16.4%) stddev-sans-first:16.28 23:13:41 INFO - PROCESS | 1678 | Values: 156.0 162.0 118.0 111.0 116.0 112.0 119.0 112.0 113.0 111.0 23:13:41 INFO - PROCESS | 1678 | 23:13:41 INFO - PROCESS | 1678 | [#1] audio-beat-detection Cycles:10 Average:141.70 Median:144.00 stddev:19.69 (13.7%) stddev-sans-first:20.70 23:13:41 INFO - PROCESS | 1678 | Values: 149.0 122.0 144.0 172.0 121.0 143.0 144.0 121.0 174.0 127.0 23:13:41 INFO - PROCESS | 1678 | 23:13:41 INFO - PROCESS | 1678 | [#2] audio-dft Cycles:10 Average:184.60 Median:184.50 stddev:1.35 (0.7%) stddev-sans-first:1.33 23:13:41 INFO - PROCESS | 1678 | Values: 186.0 187.0 184.0 184.0 184.0 183.0 184.0 183.0 185.0 186.0 23:13:41 INFO - PROCESS | 1678 | 23:13:41 INFO - PROCESS | 1678 | [#3] audio-fft Cycles:10 Average:76.60 Median:83.00 stddev:14.82 (17.9%) stddev-sans-first:14.90 23:13:41 INFO - PROCESS | 1678 | Values: 90.0 75.0 86.0 89.0 56.0 59.0 56.0 79.0 96.0 80.0 23:13:41 INFO - PROCESS | 1678 | 23:13:41 INFO - PROCESS | 1678 | [#4] audio-oscillator Cycles:10 Average:95.00 Median:77.00 stddev:22.84 (29.7%) stddev-sans-first:24.20 23:13:41 INFO - PROCESS | 1678 | Values: 92.0 90.0 131.0 77.0 77.0 80.0 123.0 77.0 76.0 127.0 23:13:41 INFO - PROCESS | 1678 | 23:13:41 INFO - PROCESS | 1678 | [#5] imaging-gaussian-blur Cycles:10 Average:100.00 Median:84.50 stddev:18.81 (22.3%) stddev-sans-first:19.36 23:13:41 INFO - PROCESS | 1678 | Values: 87.0 85.0 96.0 131.0 113.0 84.0 87.0 84.0 102.0 131.0 23:13:41 INFO - PROCESS | 1678 | 23:13:41 INFO - PROCESS | 1678 | [#6] imaging-darkroom Cycles:10 Average:93.70 Median:94.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 23:13:41 INFO - PROCESS | 1678 | Values: 94.0 94.0 94.0 94.0 93.0 94.0 94.0 94.0 93.0 93.0 23:13:41 INFO - PROCESS | 1678 | 23:13:41 INFO - PROCESS | 1678 | [#7] imaging-desaturate Cycles:10 Average:84.40 Median:84.00 stddev:0.70 (0.8%) stddev-sans-first:0.73 23:13:41 INFO - PROCESS | 1678 | Values: 84.0 86.0 85.0 84.0 85.0 84.0 84.0 84.0 84.0 84.0 23:13:41 INFO - PROCESS | 1678 | 23:13:41 INFO - PROCESS | 1678 | [#8] json-parse-financial Cycles:10 Average:59.70 Median:59.00 stddev:4.88 (8.3%) stddev-sans-first:5.17 23:13:41 INFO - PROCESS | 1678 | Values: 60.0 60.0 59.0 59.0 57.0 57.0 59.0 73.0 57.0 56.0 23:13:41 INFO - PROCESS | 1678 | 23:13:41 INFO - PROCESS | 1678 | [#9] json-stringify-tinderbox Cycles:10 Average:69.90 Median:69.50 stddev:5.20 (7.5%) stddev-sans-first:5.05 23:13:41 INFO - PROCESS | 1678 | Values: 64.0 64.0 80.0 77.0 72.0 67.0 68.0 68.0 69.0 70.0 23:13:41 INFO - PROCESS | 1678 | 23:13:41 INFO - PROCESS | 1678 | [#10] stanford-crypto-aes Cycles:10 Average:85.20 Median:85.00 stddev:0.92 (1.1%) stddev-sans-first:0.87 23:13:41 INFO - PROCESS | 1678 | Values: 84.0 86.0 85.0 85.0 85.0 84.0 85.0 85.0 87.0 86.0 23:13:41 INFO - PROCESS | 1678 | 23:13:41 INFO - PROCESS | 1678 | [#11] stanford-crypto-ccm Cycles:10 Average:155.50 Median:153.50 stddev:20.08 (13.1%) stddev-sans-first:20.93 23:13:41 INFO - PROCESS | 1678 | Values: 145.0 152.0 162.0 144.0 141.0 138.0 138.0 182.0 198.0 155.0 23:13:41 INFO - PROCESS | 1678 | 23:13:41 INFO - PROCESS | 1678 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.10 Median:169.00 stddev:3.73 (2.2%) stddev-sans-first:3.87 23:13:41 INFO - PROCESS | 1678 | Values: 167.0 178.0 168.0 168.0 170.0 169.0 170.0 169.0 169.0 163.0 23:13:41 INFO - PROCESS | 1678 | 23:13:41 INFO - PROCESS | 1678 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.40 Median:69.00 stddev:1.90 (2.7%) stddev-sans-first:1.92 23:13:41 INFO - PROCESS | 1678 | Values: 70.0 69.0 69.0 69.0 68.0 67.0 67.0 72.0 65.0 68.0 23:13:41 INFO - PROCESS | 1678 | -------- Summary: end -------- 23:13:41 INFO - PROCESS | 1678 | 23:13:42 INFO - TEST-INFO | 1678: exit 0 23:13:42 INFO - TEST-OK | kraken | took 58931ms 23:13:42 INFO - SUITE-END | took 1173s 23:13:43 INFO - Completed test suite (00:19:34) 23:13:43 INFO - javascript benchmark 23:13:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3395.9151021224466, 3442.053435305291, 3446.138465383654, 3484.1463841358864, 3514.2093029068446, 5589.0, 5678.886422271552, 5686.0333743263645, 5702.173184888194, 5716.742746576404, 5619.353774315955, 5672.943270567293, 5686.772529098841, 5690.886182276352, 5691.032524470839, 5140.999999999991, 5151.974240128794, 5175.974120129394, 5193.610479214055, 5246.291750613656, 5252.0, 5256.08018596744, 5260.368755749297, 5265.86783841474, 5267.499587539178, 5141.0, 5189.8183563575285, 5205.16717325228, 5211.86970325743, 5312.256284120207, 5211.270422140922, 5224.242484839677, 5225.085610018232, 5233.738313084331, 5301.363836339626, 5081.390233172043, 5103.9489605103845, 5131.87170320745, 5162.1224391853475, 5164.147915593922, 5500.037493438672, 5514.200440936082, 5543.0, 5561.026820306471, 5608.579356548275, 6221.720022598994, 6243.063540468966, 6243.875122497525, 6247.750089996394, 6254.499640028786, 6048.803751689035, 6185.0, 6233.2208473940755, 6293.087502312185, 6309.684515774193, 5724.513416359656, 5757.337906140764, 5760.53915686744, 5786.189933409242, 5803.419658034205, 5945.810837832364, 6257.812265632038, 6261.154744109578, 6261.655608941461, 6263.624182548971, 5130.974345128325, 5461.244310165202, 5990.191324171268, 6061.484773794277, 6123.0, 6245.594036387621, 6247.500199983991, 6250.1874756281395, 6252.0, 6252.249730032425, 5430.67415955044, 6124.112003759519, 6200.162977998003, 6201.10084037821, 6210.254769427697, 6063.939360606338, 6112.473085128069, 6123.221615589998, 6125.47964319526, 6168.347610527516, 4831.806727730922, 4859.757012149378, 4921.50784921505, 4922.58158056562, 4954.207326827763, 4397.164538737694, 4399.0, 4402.4056752338665, 4458.375827384169, 4464.352668862996, 5631.577631677558, 5664.943350566524, 5665.631733937364, 5665.886682266331, 5669.40471250521, 6117.296510901303, 6170.660613666291, 6171.506279497719, 6181.103739957681, 6271.341509141565, 5608.85977850557, 5613.298337707786, 5618.719064046782, 5631.971840140855, 5649.802256920988, 5330.387005494418, 5330.680159190461, 5334.333208348957, 5345.33183352081, 5398.082326004589, 6116.761878222017, 6234.906476402948, 6238.563300568917, 6275.3724627536885, 6293.748250069946, 6199.814005579839, 6239.875202495834, 6254.906176407449, 6262.5929314594405, 6277.435030847246, 6295.307516173309, 6298.0, 6304.275008373912, 6314.431701146918, 6338.080978258219, 6014.609050411709, 6106.236720409949, 6131.988406491147, 6138.07928810682, 6160.784372546939, 6214.689265536615, 6238.376162383725, 6240.282367527792, 6241.750329986749, 6242.937570624235, 5620.735334549857, 5872.530197584098, 6056.0, 6060.515158787374, 6080.148779170831, 5936.37668044849, 6038.245219347582, 6040.486558642388, 6084.8783024340155, 6136.509079273558, 5904.025835737055, 5915.852103697442, 5939.10913362999, 5939.2575928009, 5951.404859514014, 5897.852553686193, 6064.939350606437, 6072.14989901423, 6077.817665470043, 6122.755089796537, 5824.039033559585, 5834.241548598655, 5935.584509084322, 5970.044792833126, 5999.100134979788], "value": 5661.904162709906, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [504.5811976059871, 507.8121095194779, 509.35312153564973, 572.6335145506876, 603.2067830802497, 29714.0, 30337.241568960788, 30359.848200758966, 30384.08847734569, 30400.847995760018, 30766.692333076717, 31275.90534331299, 39744.40383394252, 40909.590904090954, 41395.37906931391, 9400.764980875481, 9563.713088607354, 9611.519424028806, 9615.855762163537, 9621.0, 9612.90387096127, 9718.708438746813, 9731.172850307697, 9737.415755054719, 9739.26955478342, 8503.702370417037, 8783.472991620523, 11517.19379643421, 11860.288382697023, 11904.047676185884, 11424.371659558721, 11521.884781152248, 11538.59614913474, 11543.249688770204, 11548.769024619545, 8410.873836892451, 11280.548778048867, 11319.773604527945, 11467.713307167303, 11474.188823738237, 31868.769186156977, 38827.805860970795, 39361.60638393608, 39469.0, 39503.0, 3118.98440507796, 3264.151320656623, 3279.3441311737747, 3343.3981883261004, 3459.238967427162, 29009.41981160386, 29190.854045729848, 29515.262118446997, 29902.551461728093, 30002.699973000428, 2722.659667541557, 2731.6039174319812, 2739.849308288043, 2755.0908200293857, 2922.254825019628, 481967.0, 482800.0, 483991.9999999965, 485988.0, 487761.0, 35258.64741352605, 36231.913042608765, 36252.0, 36327.63672363295, 36366.454503182475, 35688.64311356905, 35755.821220893726, 35855.28289434249, 36011.09972250715, 36013.45979810305, 15010.24948752558, 15143.318550665246, 15235.0, 15251.542453726406, 15257.6185595361, 12823.551175708992, 12864.485420583072, 12877.613671589866, 13167.736645267041, 13193.076484645982, 6953.443724502028, 7612.8477430451085, 7765.92234077652, 8111.716089936824, 8250.216229458192, 3322.700956913841, 3354.8490317935753, 3373.392789297901, 3397.1676939150066, 3404.540387047766, 934.8831396075491, 1000.8698869146964, 1078.191356482638, 1078.7357097511003, 1137.9487923043482, 28143.71856281452, 28654.99707510227, 30114.54828177579, 30604.0, 30702.84648576743, 976.0, 1057.4078119104747, 1058.9682309530726, 1088.3633074651418, 1094.1356328500556, 15382.230888455757, 15639.609009774847, 15691.1369874658, 15703.68592628141, 16007.318316786972, 623405.0, 687261.0, 701777.4911125412, 702093.0, 703559.0, 53942.33605471369, 55121.44878551243, 62402.37597623966, 63042.36957630457, 64017.67991160014, 1491.2990894279887, 1497.2887878258175, 1500.7598784194695, 1506.4652048522835, 1578.4396539228637, 24572.6314105285, 28945.131646050653, 29286.0, 30336.089917302517, 30992.91524796621, 48387.5161248383, 58265.0, 60213.39786602078, 60216.0, 60297.397026029175, 446449.0, 447038.0, 675543.0, 692133.5393323001, 814380.0, 42698.57301426946, 42780.0, 43089.56910430855, 43524.0, 43526.782366087966, 47419.68498040516, 47700.80747981328, 47867.0, 47910.0, 49526.00947981092, 39059.41410878896, 39177.6082239174, 39565.40651890282, 40120.59879401168, 40792.38811417772, 38155.42766858444, 38389.0, 38796.0, 39705.60294397019, 39999.00002499961, 37908.62091379051, 38680.41979370368, 38791.0, 38944.2211155781, 39206.215875682894, 28053.859730701217, 32298.83850580732, 33678.66321336755, 33930.96414215155, 34030.0, 24660.753392466562, 24897.875510623056, 25081.372965676004, 25505.48989020246, 25513.362165946], "value": 18832.542445870487, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [331.97585448890175, 354.7339495378466, 386.11772100749795, 395.9903489789318, 403.14332044405637, 19807.801921980776, 19982.60034799305, 20720.689189662167, 21246.89376553115, 21284.787152128472, 11592.362420066893, 11637.825432618496, 12252.632421027361, 12263.386830658475, 12264.018878489722, 5215.973920130404, 5406.459354064601, 5442.183672449125, 5442.6462279951875, 5443.129099344106, 3851.5378154621485, 4129.4012368206595, 5727.083666613343, 6074.787382441616, 6085.178500902366, 6048.969755151218, 6066.120412540179, 6073.240844894388, 6082.604630699013, 6231.968840155793, 6243.282022567418, 6407.679616019181, 6419.64691941943, 6423.582467139621, 6434.292227854956, 3499.5800503939436, 3654.5797233318244, 5356.982173387044, 5741.425857414267, 5753.108268218432, 13142.934285328607, 13417.12788668743, 13464.663383415394, 13530.18818870871, 13600.04799664024, 2057.1668474267944, 2178.357384571555, 2181.1384503121335, 2182.5743979923923, 2239.865608063505, 9954.104130628206, 10264.435456049914, 10308.072273495422, 10545.314554554006, 14559.417623295056, 1672.4146548707854, 1700.8604235162347, 1707.1890851845274, 1742.033171589767, 1774.9290028398848, 99832.40725038154, 106182.0, 107538.0, 107720.0, 108030.45984770026, 9097.999220085785, 11562.88437115635, 11566.0, 11570.94214528922, 11651.650450486499, 15018.399264029316, 15031.323590438456, 15049.699006019819, 15070.773938390932, 15077.472288469851, 7113.92886071143, 8665.220130188314, 8723.563821808884, 8754.0, 8775.824483510294, 8647.048824629286, 8684.348673849487, 8710.21608055278, 8801.119888011148, 8853.955730221307, 4496.123255965055, 4553.704009239389, 4554.726716396961, 4563.863084107482, 4622.14490319292, 1501.3469140923733, 1668.3159904439121, 1840.2734462712847, 1852.536865783554, 1964.7838737738837, 822.942394032424, 934.8737920380797, 935.5322338830584, 936.9109934556207, 949.6960972488876, 19464.0, 20799.8960005199, 20853.0, 20878.895605521877, 20956.79043209579, 963.2149797914677, 965.8406362950175, 984.9064338887795, 992.1071036067597, 1025.661531694539, 10534.36793792375, 10890.401027943539, 10909.67270981856, 10935.17986151026, 10975.890241097486, 248026.0, 354410.0, 592371.0, 595866.0, 603030.9393812099, 116732.0, 126539.0, 126870.36564817117, 130947.69052309546, 132354.0, 1014.6499457687086, 1029.315505189041, 1078.3853203673987, 1102.0522350778324, 1104.320842681745, 15736.741060715345, 15857.365705372118, 16694.0, 17804.55488612795, 17808.643827123127, 47122.58632241038, 47263.76368118137, 47570.286445704034, 48680.0, 50039.0, 59292.70353648204, 70731.64634176796, 74232.25767742253, 75243.62378188292, 76749.61625192061, 6819.215790184192, 7219.0976127984, 7310.086239220098, 7351.632418379167, 7563.373874616984, 11237.157213209206, 11726.70683232926, 11967.581134660246, 12004.939975300067, 12322.445489952794, 8467.61895714682, 10292.639757608447, 10392.688219353431, 10458.63394781179, 10511.842322365017, 10112.59549618007, 10154.847677284994, 10438.530266138194, 10579.598337605332, 10602.89397106019, 10867.891321087005, 11061.612843550438, 11212.719682008015, 11284.21010529288, 11326.490307935997, 7267.164276108315, 7350.338469537767, 7417.1841097478155, 7420.295071968154, 7637.770866874002, 10312.845307320245, 10472.68581942543, 10995.175361847734, 11037.668869933916, 11064.944675276894], "value": 8928.746315592542, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1391.5129704603385, 1568.4040064775384, 1581.1382796375974, 1812.6737187306287, 1946.5328321202908, 7791.805204869881, 8444.028936672285, 8540.402171847973, 8612.00961889383, 8626.051134375213, 8291.751247462586, 8488.91511084889, 8493.193146651058, 8497.447665901727, 8507.234348908598, 2362.704661917256, 2390.3904504351367, 2394.293683363412, 2394.6408038794234, 2396.0775101585928, 2364.4916342986253, 2385.296337580418, 2412.3124909400826, 2417.3714834143075, 2433.9634905476432, 1973.733545971297, 2385.355953892448, 2396.4727759892794, 2419.3709635494724, 2421.2131057406414, 2368.289513146049, 2386.809055275574, 2396.676448679423, 2397.2089210560475, 2414.420539070628, 2307.261676263596, 2378.77401646845, 2387.4866903615807, 2389.808815294772, 2390.9043638254448, 7667.233276672344, 7907.762767116938, 8566.70016549424, 8630.352723545697, 8664.826703465958, 3798.5061941947647, 4878.951210487885, 4887.8778030549165, 5106.668066575698, 5259.317018554064, 2835.7873159512915, 3360.865565377382, 3408.2672225471642, 3456.5679290088738, 3471.2710330830555, 1002.6190047781869, 1025.0159846547324, 1025.9689012542422, 1029.4543891737392, 1029.5469993202967, 10592.576296948113, 10876.293040952392, 11080.224384293102, 11180.832287515694, 11189.048930840889, 4427.247367947457, 4559.270516717309, 4690.179218636725, 4800.207965685623, 4808.543188397097, 8006.719764808204, 9644.987276336024, 9693.563789629343, 9698.030196980386, 9698.272629552812, 5264.842054738364, 5269.235960785664, 5283.128283833124, 5291.915157392726, 5294.152935530374, 4786.210275304536, 4816.518348165226, 4848.75756212195, 4853.0779151961015, 4889.706617602955, 3904.824282907276, 3929.31237033518, 3997.4403583498333, 4032.294348489003, 4057.9391309130388, 1315.7828958221978, 1328.110166188656, 1328.5482935801874, 1334.8598397168353, 1339.6918708697053, 774.9380049596019, 843.6667516331138, 853.3514528958036, 888.05865782271, 968.070652173921, 5533.861653458695, 5695.572832037614, 5887.61730487517, 5901.911471327933, 5903.704814759245, 428.88634511854383, 433.22669035770997, 437.63676148796446, 445.5544455544456, 445.9531749166356, 3709.8145092745253, 3740.2706472238197, 3801.657850793442, 3817.656410922975, 3821.426785982125, 10039.548220330104, 10476.109530689824, 10530.262881598215, 10570.365778053341, 10579.153667706565, 10177.84733229002, 10456.163506919429, 10538.631147909786, 10568.313059651098, 10705.0, 6737.225219099768, 7854.1753115922, 8054.0, 8078.0, 8159.918400815915, 5556.138798486241, 5624.0, 5663.57523185771, 5676.4323567642905, 5841.50347220491, 9628.229741620513, 9710.75723106928, 9796.0, 9871.605135794487, 9902.455364954996, 9072.818543629222, 9321.860172097287, 9420.434773913586, 9720.222382209266, 9966.053224943618, 5787.305523337226, 6689.866202676017, 6794.52438329332, 7310.780676579711, 7313.926860731324, 7747.496412733154, 7933.9603301983125, 8019.518828870287, 8031.076426210827, 8043.919560804317, 7181.928180718335, 7195.136583609791, 7357.0803649543805, 7381.44639152055, 7398.371138453292, 6156.507479401547, 7135.143782745896, 7166.820829479097, 7240.203577606564, 7243.674034668558, 6790.5925644461495, 6798.320167983162, 6878.896816547855, 6879.449644028366, 6882.311768823278, 4128.8348466061025, 4688.882777930579, 4720.433547974265, 4843.24929635907, 4860.222364421676, 4721.811127554859, 4907.3620429343955, 4929.334539837219, 4932.383452068491, 4941.838667913109], "value": 4379.649358354966, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [310.63655523038045, 316.476231836311, 319.2131396108592, 325.0557131532898, 329.1589987581728, 5247.580193584514, 5762.847430513903, 5809.186713860063, 5848.152017957394, 5903.822885313437, 4819.469858315591, 5876.618019828718, 5878.118282257664, 5911.379305172959, 5945.49215962582, 3830.7480856077023, 4195.496540415147, 4344.761038142901, 4344.913101737963, 4365.519792822787, 3996.220736956295, 4071.674266058723, 4175.352820312856, 4296.935545966813, 4392.670549708769, 3998.660113890323, 4016.533965102731, 4264.445622069142, 4367.235733746597, 4370.519242883281, 4193.5387107418155, 4195.0, 4389.6049355558, 4415.779211039451, 4415.823367065345, 2976.9851150744325, 3377.273886114497, 3796.49431375291, 3797.708779015148, 3809.580946095927, 5039.52124548171, 5597.328320601513, 5598.10430331145, 5599.272094627713, 5618.550515958714, 4851.587615052689, 5061.924071138899, 5065.113605119126, 5080.1617733074, 5134.64057515974, 4905.779239934212, 6183.690815459209, 6189.412005859436, 6218.875622487525, 6238.189035425366, 3566.7503274770775, 4257.467816522934, 4259.188026716071, 4262.701610887239, 4268.975445892995, 6237.469815065725, 6546.869062618721, 6547.377999090078, 6555.836104097388, 6556.213254409454, 3806.314863324573, 4294.463192100988, 4321.114171594815, 4328.156009578165, 4330.41539392178, 3924.6271604197555, 4223.732880135947, 4413.3159360299205, 4414.315781053878, 4462.286034234507, 4702.224133018014, 5017.874553136127, 5080.034793389244, 5096.789022915614, 5110.156824124052, 5522.392536820947, 6249.7187626556915, 6268.49852011838, 6276.426665067301, 6320.525960552977, 1455.5851582299206, 1481.9481318153812, 1483.406637345049, 1486.3757221966584, 1488.7171437426855, 815.0219736316444, 931.8072866730596, 961.4375590279625, 967.7822489939707, 986.2208855004609, 5350.678959262456, 5739.16782066598, 5761.567882408836, 5776.08307804556, 5791.276090488689, 5023.372078490189, 5396.9730151348995, 6459.160309159779, 6462.870742585122, 6493.701259747975, 5611.579131565149, 5770.4806567408295, 5772.163036359704, 5816.011278082736, 5834.795782147605, 3380.814055226937, 3383.915402114967, 3404.0766966663755, 3409.488576713513, 3416.914577135542, 5760.855978600484, 6279.937200628026, 6778.204635037071, 6778.932210677929, 6800.285969973181, 5783.797567085132, 6555.311692272338, 6737.797866064026, 6747.730090796412, 6756.729730810713, 5914.6746928918465, 6441.937080381778, 6663.766768163188, 6851.246362899978, 6893.620850853151, 4864.92702609454, 6294.559380843297, 6469.838254043499, 6533.738650453929, 6537.444317232898, 5875.500582450369, 5943.702814859326, 6370.1718776558755, 6416.0, 6422.935770642233, 5394.921015796778, 5620.3536593290655, 5764.818212266391, 5941.970290148694, 6497.935020649733, 4014.0366312084307, 4202.117555313296, 4220.535741068541, 4223.831046758095, 4225.683073769541, 5887.470127688529, 6446.51887980504, 6449.258335291502, 6457.483401327976, 6474.093626892145, 5461.371942226536, 6100.603460775035, 6170.629762214102, 6274.686265686606, 6290.955439482095, 6017.247844019497, 6326.715297811517, 6342.365763423621, 6344.206974128234, 6349.269833969152, 6156.722947467464, 6165.691715414301, 6193.84515387119, 6210.944139496206, 6238.656873871981, 5471.15499940021, 5628.268325117709, 5755.280589926259, 5879.059350503899, 5947.434993675594, 5358.598105142052, 5922.763089476545, 6032.819015429544, 6160.0, 6170.845728856814], "value": 4507.16821096635, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [622.411820829316, 657.8026592022393, 691.1740470138185, 769.146247665092, 810.3719617296598, 14872.47946321879, 14949.701005979887, 15334.46329378472, 15359.769603455958, 15710.293036813342, 14303.713925721479, 14398.856011439882, 14422.855771442282, 14443.844492440607, 14450.277486125678, 727.0729819480152, 911.4258017448998, 915.7115508614799, 921.3734660430904, 921.741912264567, 747.6112421540796, 905.2531661379388, 939.7133874168376, 941.7598512379368, 942.1708896171359, 973.9967833131885, 979.9559019844088, 981.1856159387727, 981.7643765496265, 981.7741919358551, 762.9661808249797, 943.4575119306427, 970.2868391732071, 971.9805603887918, 974.1768205866067, 729.0595132279353, 729.956202627844, 731.6926890705917, 731.7219456606508, 732.6886073418775, 14937.551873443705, 15032.924835375754, 15216.923915380463, 15313.540593782205, 15320.310586023656, 12866.935665321707, 12876.742465150739, 13050.673733156747, 13105.344732763324, 13232.073754837165, 9995.360971302889, 10380.532876020521, 10424.426656533888, 10742.3017503862, 10878.0, 3516.208853008078, 3556.039869235292, 3560.394732895414, 3601.549806274216, 3674.3386190485703, 88653.11346886514, 92689.07310926872, 92797.53601231951, 92869.53565232198, 93204.0, 9588.616455341848, 9598.184154346958, 9649.324547281763, 9888.604455821831, 10021.198304135653, 20752.58494830125, 21373.824439655968, 21384.893075534525, 21403.929803510073, 22525.21161759363, 7463.328300452985, 7479.289467500579, 7597.012388389474, 7784.844303113906, 7922.801929951797, 3488.2500262443696, 4059.5331536873045, 4072.226277007359, 4074.4499492568716, 4171.687123465692, 3454.360943225511, 3555.6444355564236, 3565.3404120237838, 3567.3578755823683, 3631.7276204284785, 630.1618846933567, 637.3117033603697, 644.2880616918331, 644.713102669317, 662.2384258103219, 460.82028009076487, 482.97826597803186, 537.3659082282866, 570.4865620941187, 590.1354515634616, 6308.432241098323, 7567.9243207567215, 7580.128285247157, 7677.961610191914, 8067.838643227103, 475.6266330930203, 482.39700374531833, 532.2947095099009, 569.7379205565403, 579.6580017789524, 3641.5812181598926, 3717.182219911615, 3748.8125593720206, 3797.6202379762353, 3976.1252524444576, 70714.9392759109, 84478.0, 89231.0, 89344.0, 89457.0, 55871.16193257104, 75860.62069689727, 76851.8472222917, 77415.0, 77584.0, 68119.65940170268, 69689.65155174192, 69808.0, 70060.59878802396, 70616.0, 7261.841099121899, 7367.742129025459, 7443.218462061622, 7688.192739762469, 7735.651895664596, 29109.0, 39353.0, 42051.0, 42913.57086429096, 43300.133997319244, 46371.536284638074, 52363.95272094613, 53945.730271348395, 55379.0, 56147.15779263232, 8796.736097917073, 10218.948905255425, 10522.789544209227, 10625.893741062491, 10788.136949044214, 11589.710257243636, 11608.535658573563, 11676.532938682698, 11766.411679416165, 11832.82250766222, 10960.835587466354, 11042.668719938414, 11068.059214966495, 11157.49791259379, 11463.541458341573, 10848.783024339627, 10852.945735271272, 11134.55461781552, 11208.71978200519, 11218.158638102339, 8814.64262069861, 10648.201384896318, 10695.77813951396, 10932.0, 11016.889831101586, 9084.0, 9319.712932051061, 9521.476318802255, 9543.42739435636, 9752.9024709752, 9138.63445462174, 9341.392809467363, 9366.484843333683, 9420.0, 9616.711498655051], "value": 6710.459271954969, "unit": "score"}], "name": "dromaeo_css", "value": 7081.626151051101, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 162.0, 118.0, 111.0, 116.0, 112.0, 119.0, 112.0, 113.0, 111.0], "value": 123.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 122.0, 144.0, 172.0, 121.0, 143.0, 144.0, 121.0, 174.0, 127.0], "value": 141.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 187.0, 184.0, 184.0, 184.0, 183.0, 184.0, 183.0, 185.0, 186.0], "value": 184.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 75.0, 86.0, 89.0, 56.0, 59.0, 56.0, 79.0, 96.0, 80.0], "value": 76.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 90.0, 131.0, 77.0, 77.0, 80.0, 123.0, 77.0, 76.0, 127.0], "value": 95.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 96.0, 131.0, 113.0, 84.0, 87.0, 84.0, 102.0, 131.0], "value": 100.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 93.0, 94.0, 94.0, 94.0, 93.0, 93.0], "value": 93.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 85.0, 84.0, 85.0, 84.0, 84.0, 84.0, 84.0, 84.0], "value": 84.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 59.0, 59.0, 57.0, 57.0, 59.0, 73.0, 57.0, 56.0], "value": 59.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 80.0, 77.0, 72.0, 67.0, 68.0, 68.0, 69.0, 70.0], "value": 69.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 85.0, 85.0, 85.0, 84.0, 85.0, 85.0, 87.0, 86.0], "value": 85.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 152.0, 162.0, 144.0, 141.0, 138.0, 138.0, 182.0, 198.0, 155.0], "value": 155.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 178.0, 168.0, 168.0, 170.0, 169.0, 170.0, 169.0, 169.0, 163.0], "value": 169.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 69.0, 69.0, 68.0, 67.0, 67.0, 72.0, 65.0, 68.0], "value": 68.4, "unit": "score"}], "name": "kraken", "value": 1506.8000000000002, "alertThreshold": 2.0}]} 23:13:43 INFO - Return code: 0 23:13:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 23:13:43 ERROR - # TBPL SUCCESS # 23:13:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:13:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:13:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:13:43 INFO - 2016-04-14 23:13:43.253 screenresolution[1682:10202] starting screenresolution argv=screenresolution get 23:13:43 INFO - 2016-04-14 23:13:43.255 screenresolution[1682:10202] Display 0: 1600x1200x32@60 23:13:43 INFO - 2016-04-14 23:13:43.261 screenresolution[1683:10206] starting screenresolution argv=screenresolution list 23:13:43 INFO - Available Modes on Display 0 23:13:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:13:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:13:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:13:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:13:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:13:43 INFO - Intel Iris: 23:13:43 INFO - Chipset Model: Intel Iris 23:13:43 INFO - Type: GPU 23:13:43 INFO - Bus: Built-In 23:13:43 INFO - VRAM (Dynamic, Max): 1536 MB 23:13:43 INFO - Vendor: Intel (0x8086) 23:13:43 INFO - Device ID: 0x0a2e 23:13:43 INFO - Revision ID: 0x0009 23:13:43 INFO - Displays: 23:13:43 INFO - Display: 23:13:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:13:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:13:43 INFO - Display Serial Number: 1600x1200 60 23:13:43 INFO - Main Display: Yes 23:13:43 INFO - Mirror: Off 23:13:43 INFO - Online: Yes 23:13:43 INFO - Rotation: Supported 23:13:43 INFO - Return code: 0 23:13:43 INFO - Running post-action listener: _resource_record_post_action 23:13:43 INFO - Running post-run listener: _resource_record_post_run 23:13:44 INFO - Total resource usage - Wall time: 1209s; CPU: 53.0%; Read bytes: 32235008; Write bytes: 693804032; Read time: 353; Write time: 1981 23:13:44 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 383683072; Write bytes: 377944064; Read time: 28228; Write time: 689 23:13:44 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 30506496; Write bytes: 296182784; Read time: 336; Write time: 1252 23:13:44 INFO - Running post-run listener: _upload_blobber_files 23:13:44 INFO - Blob upload gear active. 23:13:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:13:44 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 23:13:44 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'] 23:13:44 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 23:13:45 INFO - (blobuploader) - INFO - Open directory for files ... 23:13:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:13:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:13:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:13:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:13:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:13:46 INFO - (blobuploader) - INFO - Done attempting. 23:13:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:13:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:13:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:13:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:13:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:13:47 INFO - (blobuploader) - INFO - Done attempting. 23:13:47 INFO - (blobuploader) - INFO - Iteration through files over. 23:13:47 INFO - Return code: 0 23:13:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:13:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:13:47 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/39a0b8c0304ce0981f129161eb88bcf54fafe25ed9d6453ee6d8f97aef2c95d95bb799745888acf4988b117b4ac65a7cd059ed6324da67944b009575a0e64ba6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/36bb702c6985c51fb3391fdc137b623fd4b6ec6d1c8873f41a74c8ec6a3fa6c43b17dde1afaef22944cc1b9ac32dce75c69985ab8573ae76a259fd11414dbc3c"} 23:13:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:13:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:13:47 INFO - Contents: 23:13:47 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/39a0b8c0304ce0981f129161eb88bcf54fafe25ed9d6453ee6d8f97aef2c95d95bb799745888acf4988b117b4ac65a7cd059ed6324da67944b009575a0e64ba6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/36bb702c6985c51fb3391fdc137b623fd4b6ec6d1c8873f41a74c8ec6a3fa6c43b17dde1afaef22944cc1b9ac32dce75c69985ab8573ae76a259fd11414dbc3c"} 23:13:47 INFO - Running post-run listener: copy_logs_to_upload_dir 23:13:47 INFO - Copying logs to upload dir... 23:13:47 INFO - mkdir: /builds/slave/test/build/upload/logs 23:13:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.660603 ========= master_lag: 1.19 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-04-14 23:13:48.896478) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-04-14 23:13:48.906100) ========= 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.ue6CevGy7Z/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.bYy3CyWRFS/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/39a0b8c0304ce0981f129161eb88bcf54fafe25ed9d6453ee6d8f97aef2c95d95bb799745888acf4988b117b4ac65a7cd059ed6324da67944b009575a0e64ba6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/36bb702c6985c51fb3391fdc137b623fd4b6ec6d1c8873f41a74c8ec6a3fa6c43b17dde1afaef22944cc1b9ac32dce75c69985ab8573ae76a259fd11414dbc3c"} build_url:https://queue.taskcluster.net/v1/task/VP6EYMVjQjed8ZKaDJkvEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010992 build_url: 'https://queue.taskcluster.net/v1/task/VP6EYMVjQjed8ZKaDJkvEQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/39a0b8c0304ce0981f129161eb88bcf54fafe25ed9d6453ee6d8f97aef2c95d95bb799745888acf4988b117b4ac65a7cd059ed6324da67944b009575a0e64ba6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/36bb702c6985c51fb3391fdc137b623fd4b6ec6d1c8873f41a74c8ec6a3fa6c43b17dde1afaef22944cc1b9ac32dce75c69985ab8573ae76a259fd11414dbc3c"}' ========= master_lag: 1.33 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-04-14 23:13:50.243572) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 5 secs) (at 2016-04-14 23:13:50.243959) ========= 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.ue6CevGy7Z/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.bYy3CyWRFS/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.005048 ========= master_lag: 5.88 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 5 secs) (at 2016-04-14 23:13:56.131283) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-14 23:13:56.131626) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-14 23:13:56.168028) ========= ========= Total master_lag: 12.38 =========