builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0029 starttime: 1460751637.87 results: success (0) buildid: 20160415122040 builduid: b84a3658e1014dfbae3dae8c4354d197 revision: 355e9b83a662dd7e90a73b7c9cd556372c063ae9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-15 13:20:37.873233) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-15 13:20:37.873667) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 8 secs) (at 2016-04-15 13:20:37.873967) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 8 secs) (at 2016-04-15 13:20:46.076473) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-15 13:20:46.080946) ========= 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.iod25OT5aO/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.o0E7P3bGLA/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-15 13:20:46-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 20.6M=0.001s 2016-04-15 13:20:46 (20.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.481253 ========= master_lag: 1.57 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-15 13:20:48.131466) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2016-04-15 13:20:48.131747) ========= 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.iod25OT5aO/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.o0E7P3bGLA/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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.027993 ========= master_lag: 0.99 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 1 secs) (at 2016-04-15 13:20:49.151189) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 7 secs) (at 2016-04-15 13:20:49.151514) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 355e9b83a662dd7e90a73b7c9cd556372c063ae9 --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 355e9b83a662dd7e90a73b7c9cd556372c063ae9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.iod25OT5aO/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.o0E7P3bGLA/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-15 13:20:49,647 truncating revision to first 12 chars 2016-04-15 13:20:49,648 Setting DEBUG logging. 2016-04-15 13:20:49,648 attempt 1/10 2016-04-15 13:20:49,648 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/355e9b83a662?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-15 13:20:50,654 unpacking tar archive at: fx-team-355e9b83a662/testing/mozharness/ program finished with exit code 0 elapsedTime=1.428190 ========= master_lag: 6.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 7 secs) (at 2016-04-15 13:20:56.589556) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-15 13:20:56.589828) ========= script_repo_revision: 355e9b83a662dd7e90a73b7c9cd556372c063ae9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-15 13:20:56.590195) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 20 secs) (at 2016-04-15 13:20:56.590651) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 20 secs) (at 2016-04-15 13:21:17.041317) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-04-15 13:21:17.041580) ========= /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.iod25OT5aO/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.o0E7P3bGLA/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 13:21:17 INFO - MultiFileLogger online at 20160415 13:21:17 in /builds/slave/test 13:21:17 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 13:21:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:21:17 INFO - {'append_to_log': False, 13:21:17 INFO - 'base_work_dir': '/builds/slave/test', 13:21:17 INFO - 'blob_upload_branch': 'Fx-Team', 13:21:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:21:17 INFO - 'branch': 'Fx-Team', 13:21:17 INFO - 'buildbot_json_path': 'buildprops.json', 13:21:17 INFO - 'config_files': ('talos/mac_config.py',), 13:21:17 INFO - 'default_actions': ('clobber', 13:21:17 INFO - 'read-buildbot-config', 13:21:17 INFO - 'download-and-extract', 13:21:17 INFO - 'populate-webroot', 13:21:17 INFO - 'create-virtualenv', 13:21:17 INFO - 'install', 13:21:17 INFO - 'run-tests'), 13:21:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:21:17 INFO - 'download_minidump_stackwalk': True, 13:21:17 INFO - 'download_symbols': 'ondemand', 13:21:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:21:17 INFO - 'tooltool.py': '/tools/tooltool.py', 13:21:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:21:17 INFO - '/tools/misc-python/virtualenv.py')}, 13:21:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:21:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:21:17 INFO - 'installer_path': 'installer.exe', 13:21:17 INFO - 'log_level': 'info', 13:21:17 INFO - 'log_name': 'talos', 13:21:17 INFO - 'log_to_console': True, 13:21:17 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:21:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:21:17 INFO - 'opt_config_files': (), 13:21:17 INFO - 'pip_index': False, 13:21:17 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:21:17 INFO - 'cmd': ('bash', 13:21:17 INFO - '-c', 13:21:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:21:17 INFO - 'enabled': True, 13:21:17 INFO - 'halt_on_failure': False, 13:21:17 INFO - 'name': 'check_screen_resolution'},), 13:21:17 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:21:17 INFO - 'cmd': ('bash', 13:21:17 INFO - '-c', 13:21:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:21:17 INFO - 'enabled': True, 13:21:17 INFO - 'halt_on_failure': False, 13:21:17 INFO - 'name': 'check_screen_resolution'},), 13:21:17 INFO - 'run_cmd_checks_enabled': True, 13:21:17 INFO - 'sps_profile': False, 13:21:17 INFO - 'sps_profile_interval': 0, 13:21:17 INFO - 'suite': 'dromaeojs', 13:21:17 INFO - 'system_bits': '32', 13:21:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:21:17 INFO - 'title': 't-yosemite-r7-0029', 13:21:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:21:17 INFO - 'use_talos_json': True, 13:21:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:21:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:21:17 INFO - 'work_dir': 'build'} 13:21:17 INFO - ##### 13:21:17 INFO - ##### Running clobber step. 13:21:17 INFO - ##### 13:21:17 INFO - Running pre-action listener: _resource_record_pre_action 13:21:17 INFO - Running main action method: clobber 13:21:17 INFO - rmtree: /builds/slave/test/build 13:21:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:21:18 INFO - Running post-action listener: _resource_record_post_action 13:21:18 INFO - ##### 13:21:18 INFO - ##### Running read-buildbot-config step. 13:21:18 INFO - ##### 13:21:18 INFO - Running pre-action listener: _resource_record_pre_action 13:21:18 INFO - Running main action method: read_buildbot_config 13:21:18 INFO - Using buildbot properties: 13:21:18 INFO - { 13:21:18 INFO - "project": "", 13:21:18 INFO - "product": "firefox", 13:21:18 INFO - "script_repo_revision": "production", 13:21:18 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 13:21:18 INFO - "repository": "", 13:21:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 13:21:18 INFO - "buildid": "20160415122040", 13:21:18 INFO - "pgo_build": "False", 13:21:18 INFO - "basedir": "/builds/slave/test", 13:21:18 INFO - "buildnumber": 730, 13:21:18 INFO - "slavename": "t-yosemite-r7-0029", 13:21:18 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 13:21:18 INFO - "platform": "yosemite_r7", 13:21:18 INFO - "scheduler": "tests-fx-team-macosx64-talos", 13:21:18 INFO - "branch": "fx-team", 13:21:18 INFO - "repo_path": "integration/fx-team", 13:21:18 INFO - "revision": "355e9b83a662dd7e90a73b7c9cd556372c063ae9", 13:21:18 INFO - "stage_platform": "macosx64", 13:21:18 INFO - "builduid": "b84a3658e1014dfbae3dae8c4354d197", 13:21:18 INFO - "slavebuilddir": "test" 13:21:18 INFO - } 13:21:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/ekxJUFGQRouhYelxehikiQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 13:21:18 INFO - Running post-action listener: _resource_record_post_action 13:21:18 INFO - ##### 13:21:18 INFO - ##### Running download-and-extract step. 13:21:18 INFO - ##### 13:21:18 INFO - Running pre-action listener: _resource_record_pre_action 13:21:18 INFO - Running main action method: download_and_extract 13:21:18 INFO - mkdir: /builds/slave/test/build/tests 13:21:18 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')]} 13:21:18 INFO - https://queue.taskcluster.net/v1/task/ekxJUFGQRouhYelxehikiQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 13:21:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ekxJUFGQRouhYelxehikiQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 13:21:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ekxJUFGQRouhYelxehikiQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 13:21:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ekxJUFGQRouhYelxehikiQ/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 13:21:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ekxJUFGQRouhYelxehikiQ/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 13:21:20 INFO - Downloaded 1199 bytes. 13:21:20 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 13:21:20 INFO - Using the following test package requirements: 13:21:20 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 13:21:20 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 13:21:20 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 13:21:20 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 13:21:20 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 13:21:20 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 13:21:20 INFO - u'jsshell-mac.zip'], 13:21:20 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 13:21:20 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 13:21:20 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 13:21:20 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 13:21:20 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 13:21:20 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 13:21:20 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 13:21:20 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 13:21:20 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 13:21:20 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 13:21:20 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 13:21:20 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 13:21: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')]} 13:21:20 INFO - https://queue.taskcluster.net/v1/task/ekxJUFGQRouhYelxehikiQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:21:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ekxJUFGQRouhYelxehikiQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 13:21:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ekxJUFGQRouhYelxehikiQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 13:21:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ekxJUFGQRouhYelxehikiQ/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 13:21:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ekxJUFGQRouhYelxehikiQ/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 13:21:21 INFO - Downloaded 18033943 bytes. 13:21:21 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'] 13:21:21 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 13:21:24 INFO - Return code: 0 13:21:24 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 13:21:24 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')]} 13:21:24 INFO - https://queue.taskcluster.net/v1/task/ekxJUFGQRouhYelxehikiQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:21:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ekxJUFGQRouhYelxehikiQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 13:21:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ekxJUFGQRouhYelxehikiQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 13:21:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ekxJUFGQRouhYelxehikiQ/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 13:21:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ekxJUFGQRouhYelxehikiQ/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 13:21:25 INFO - Downloaded 18033943 bytes. 13:21:25 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'] 13:21:25 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 13:21:29 INFO - Return code: 0 13:21: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')]} 13:21:29 INFO - https://queue.taskcluster.net/v1/task/ekxJUFGQRouhYelxehikiQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 13:21:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ekxJUFGQRouhYelxehikiQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 13:21:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ekxJUFGQRouhYelxehikiQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 13:21:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ekxJUFGQRouhYelxehikiQ/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 13:21:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ekxJUFGQRouhYelxehikiQ/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 13:21:30 INFO - Downloaded 10841076 bytes. 13:21:30 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'] 13:21:30 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 13:21:31 INFO - Return code: 0 13:21:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:21:31 INFO - https://queue.taskcluster.net/v1/task/ekxJUFGQRouhYelxehikiQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 13:21:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ekxJUFGQRouhYelxehikiQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 13:21:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ekxJUFGQRouhYelxehikiQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 13:21:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ekxJUFGQRouhYelxehikiQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 13:21:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ekxJUFGQRouhYelxehikiQ/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 13:21:35 INFO - Downloaded 109948379 bytes. 13:21:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ekxJUFGQRouhYelxehikiQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 13:21:35 INFO - mkdir: /builds/slave/test/properties 13:21:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:21:35 INFO - Writing to file /builds/slave/test/properties/build_url 13:21:35 INFO - Contents: 13:21:35 INFO - build_url:https://queue.taskcluster.net/v1/task/ekxJUFGQRouhYelxehikiQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 13:21:36 INFO - Running post-action listener: _resource_record_post_action 13:21:36 INFO - Running post-action listener: set_extra_try_arguments 13:21:36 INFO - ##### 13:21:36 INFO - ##### Running populate-webroot step. 13:21:36 INFO - ##### 13:21:36 INFO - Running pre-action listener: _resource_record_pre_action 13:21:36 INFO - Running main action method: populate_webroot 13:21:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:21:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:21:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:21:36 INFO - '--tppagecycles', 13:21:36 INFO - '7'], 13:21:36 INFO - 'tests': ['tsvgm']}}, 13:21:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:21:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:21:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:21:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:21:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:21:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:21:36 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:21:36 INFO - 'talos_options': ['--disable-e10s'], 13:21:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:21:36 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:21:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:21:36 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:21:36 INFO - 'talos_options': ['--disable-e10s'], 13:21:36 INFO - 'tests': ['damp', 'tps']}, 13:21:36 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:21:36 INFO - 'tests': ['damp', 'tps']}, 13:21:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:21:36 INFO - 'tests': ['dromaeo_dom']}, 13:21:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:21:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:21:36 INFO - 'tests': ['a11yr', 13:21:36 INFO - 'ts_paint', 13:21:36 INFO - 'tpaint', 13:21:36 INFO - 'sessionrestore', 13:21:36 INFO - 'sessionrestore_no_auto_restore', 13:21:36 INFO - 'tabpaint']}, 13:21:36 INFO - 'other-e10s': {'tests': ['a11yr', 13:21:36 INFO - 'ts_paint', 13:21:36 INFO - 'tpaint', 13:21:36 INFO - 'sessionrestore', 13:21:36 INFO - 'sessionrestore_no_auto_restore', 13:21:36 INFO - 'tabpaint']}, 13:21:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:21:36 INFO - 'ts_paint', 13:21:36 INFO - 'tpaint', 13:21:36 INFO - 'sessionrestore', 13:21:36 INFO - 'sessionrestore_no_auto_restore', 13:21:36 INFO - 'tabpaint']}, 13:21:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:21:36 INFO - 'ts_paint', 13:21:36 INFO - 'tpaint', 13:21:36 INFO - 'sessionrestore', 13:21:36 INFO - 'sessionrestore_no_auto_restore', 13:21:36 INFO - 'tabpaint']}, 13:21:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:21:36 INFO - 'tests': ['a11yr', 13:21:36 INFO - 'ts_paint', 13:21:36 INFO - 'tpaint', 13:21:36 INFO - 'sessionrestore', 13:21:36 INFO - 'sessionrestore_no_auto_restore', 13:21:36 INFO - 'tabpaint']}, 13:21:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:21:36 INFO - 'tests': ['a11yr', 13:21:36 INFO - 'ts_paint', 13:21:36 INFO - 'tpaint', 13:21:36 INFO - 'sessionrestore', 13:21:36 INFO - 'sessionrestore_no_auto_restore', 13:21:36 INFO - 'tabpaint']}, 13:21:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:21:36 INFO - 'tests': ['tsvgx', 13:21:36 INFO - 'tsvgr_opacity', 13:21:36 INFO - 'tart', 13:21:36 INFO - 'tscrollx', 13:21:36 INFO - 'cart']}, 13:21:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:21:36 INFO - 'tsvgr_opacity', 13:21:36 INFO - 'tart', 13:21:36 INFO - 'tscrollx', 13:21:36 INFO - 'cart']}, 13:21:36 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:21:36 INFO - 'talos_options': ['--disable-e10s'], 13:21:36 INFO - 'tests': ['tp5o']}, 13:21:36 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:21:36 INFO - 'tests': ['tp5o']}, 13:21:36 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:21:36 INFO - 'talos_options': ['--disable-e10s', 13:21:36 INFO - '--xperf_path', 13:21:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:21:36 INFO - 'tests': ['tp5n']}, 13:21:36 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:21:36 INFO - 'talos_options': ['--xperf_path', 13:21:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:21:36 INFO - 'tests': ['tp5n']}}, 13:21:36 INFO - 'talos.zip': {'path': '', 13:21:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:21:36 INFO - Running post-action listener: _resource_record_post_action 13:21:36 INFO - ##### 13:21:36 INFO - ##### Running create-virtualenv step. 13:21:36 INFO - ##### 13:21:36 INFO - Running pre-action listener: _resource_record_pre_action 13:21:36 INFO - Running main action method: create_virtualenv 13:21:36 INFO - Creating virtualenv /builds/slave/test/build/venv 13:21:36 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 13:21:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:21:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:21:36 INFO - Using real prefix '/tools/python27' 13:21:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:21:37 INFO - Installing distribute.............................................................................................................................................................................................done. 13:21:40 INFO - Installing pip.................done. 13:21:40 INFO - Return code: 0 13:21:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:21: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')]} 13:21:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:21:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:21: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')]} 13:21:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:21:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:21:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c36ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c37c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb500dbad10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c20be00>, '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 0x10c034db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c206b20>, '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.iod25OT5aO/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.o0E7P3bGLA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 13:21:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 13:21:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 13:21:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iod25OT5aO/Render', 13:21:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:21:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:21:40 INFO - 'HOME': '/Users/cltbld', 13:21:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:21:40 INFO - 'LOGNAME': 'cltbld', 13:21:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:21:40 INFO - 'MOZ_NO_REMOTE': '1', 13:21:40 INFO - 'NO_EM_RESTART': '1', 13:21:40 INFO - 'PAGER': '/bin/cat', 13:21:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:21:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:21:40 INFO - 'PWD': '/builds/slave/test', 13:21:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:21:40 INFO - 'SHELL': '/bin/bash', 13:21:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o0E7P3bGLA/Listeners', 13:21:40 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 13:21:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:21:40 INFO - 'USER': 'cltbld', 13:21:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:21:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:21:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:21:40 INFO - 'XPC_FLAGS': '0x0', 13:21:40 INFO - 'XPC_SERVICE_NAME': '0', 13:21:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:21:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:21:40 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 13:21:40 INFO - Cleaning up... 13:21:40 INFO - Return code: 0 13:21:40 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 13:21: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')]} 13:21:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:21:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:21: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')]} 13:21:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:21:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:21:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c36ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c37c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb500dbad10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c20be00>, '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 0x10c034db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c206b20>, '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.iod25OT5aO/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.o0E7P3bGLA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 13:21:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 13:21:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 13:21:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iod25OT5aO/Render', 13:21:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:21:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:21:40 INFO - 'HOME': '/Users/cltbld', 13:21:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:21:40 INFO - 'LOGNAME': 'cltbld', 13:21:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:21:40 INFO - 'MOZ_NO_REMOTE': '1', 13:21:40 INFO - 'NO_EM_RESTART': '1', 13:21:40 INFO - 'PAGER': '/bin/cat', 13:21:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:21:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:21:40 INFO - 'PWD': '/builds/slave/test', 13:21:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:21:40 INFO - 'SHELL': '/bin/bash', 13:21:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o0E7P3bGLA/Listeners', 13:21:40 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 13:21:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:21:40 INFO - 'USER': 'cltbld', 13:21:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:21:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:21:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:21:40 INFO - 'XPC_FLAGS': '0x0', 13:21:40 INFO - 'XPC_SERVICE_NAME': '0', 13:21:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:21:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:21:40 INFO - Downloading/unpacking psutil>=0.7.1 13:21:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:21:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:21:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:21:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:21:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:21:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:21:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:21:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:21:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:21:44 INFO - Installing collected packages: psutil 13:21:44 INFO - Running setup.py install for psutil 13:21:44 INFO - building 'psutil._psutil_osx' extension 13:21:44 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 13:21:44 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 13:21:44 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 13:21:45 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 13:21:45 INFO - building 'psutil._psutil_posix' extension 13:21:45 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 13:21:45 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:21:45 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:21:45 INFO - ^ 13:21:45 INFO - 1 warning generated. 13:21:45 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 13:21:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:21:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:21:45 INFO - Successfully installed psutil 13:21:45 INFO - Cleaning up... 13:21:45 INFO - Return code: 0 13:21:45 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 13:21: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')]} 13:21:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:21:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:21: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')]} 13:21:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:21:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:21:45 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 0x10c36ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c37c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb500dbad10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c20be00>, '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 0x10c034db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c206b20>, '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.iod25OT5aO/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.o0E7P3bGLA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 13:21:45 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 13:21:45 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 13:21:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iod25OT5aO/Render', 13:21:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:21:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:21:45 INFO - 'HOME': '/Users/cltbld', 13:21:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:21:45 INFO - 'LOGNAME': 'cltbld', 13:21:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:21:45 INFO - 'MOZ_NO_REMOTE': '1', 13:21:45 INFO - 'NO_EM_RESTART': '1', 13:21:45 INFO - 'PAGER': '/bin/cat', 13:21:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:21:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:21:45 INFO - 'PWD': '/builds/slave/test', 13:21:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:21:45 INFO - 'SHELL': '/bin/bash', 13:21:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o0E7P3bGLA/Listeners', 13:21:45 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 13:21:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:21:45 INFO - 'USER': 'cltbld', 13:21:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:21:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:21:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:21:45 INFO - 'XPC_FLAGS': '0x0', 13:21:45 INFO - 'XPC_SERVICE_NAME': '0', 13:21:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:21:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:21:45 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 13:21:45 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 13:21:45 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 13:21:45 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 13:21:45 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 13:21:45 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 13:21:45 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 13:21:48 INFO - Downloading mozsystemmonitor-0.0.tar.gz 13:21:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:21:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 13:21:48 INFO - Installing collected packages: mozsystemmonitor 13:21:48 INFO - Running setup.py install for mozsystemmonitor 13:21:48 INFO - Successfully installed mozsystemmonitor 13:21:48 INFO - Cleaning up... 13:21:48 INFO - Return code: 0 13:21:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:21:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:21:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:21:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:21:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:21:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:21:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:21:48 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 0x10c36ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c37c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb500dbad10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c20be00>, '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 0x10c034db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c206b20>, '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.iod25OT5aO/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.o0E7P3bGLA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 13:21:48 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 13:21:48 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 13:21:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iod25OT5aO/Render', 13:21:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:21:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:21:48 INFO - 'HOME': '/Users/cltbld', 13:21:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:21:48 INFO - 'LOGNAME': 'cltbld', 13:21:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:21:48 INFO - 'MOZ_NO_REMOTE': '1', 13:21:48 INFO - 'NO_EM_RESTART': '1', 13:21:48 INFO - 'PAGER': '/bin/cat', 13:21:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:21:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:21:48 INFO - 'PWD': '/builds/slave/test', 13:21:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:21:48 INFO - 'SHELL': '/bin/bash', 13:21:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o0E7P3bGLA/Listeners', 13:21:48 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 13:21:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:21:48 INFO - 'USER': 'cltbld', 13:21:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:21:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:21:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:21:48 INFO - 'XPC_FLAGS': '0x0', 13:21:48 INFO - 'XPC_SERVICE_NAME': '0', 13:21:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:21:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:21:48 INFO - Downloading/unpacking blobuploader==1.2.4 13:21:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:21:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:21:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:21:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:21:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:21:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:21:51 INFO - Downloading blobuploader-1.2.4.tar.gz 13:21:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:21:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:21:51 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 13:21:51 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 13:21:51 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 13:21:51 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 13:21:51 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 13:21:51 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 13:21:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:21:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:21:51 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 13:21:51 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 13:21:51 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 13:21:51 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 13:21:51 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 13:21:51 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 13:21:52 INFO - Downloading docopt-0.6.1.tar.gz 13:21:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:21:52 INFO - Installing collected packages: blobuploader, requests, docopt 13:21:52 INFO - Running setup.py install for blobuploader 13:21:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:21:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:21:52 INFO - Running setup.py install for requests 13:21:53 INFO - Running setup.py install for docopt 13:21:53 INFO - Successfully installed blobuploader requests docopt 13:21:53 INFO - Cleaning up... 13:21:53 INFO - Return code: 0 13:21:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:21:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:21:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:21:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:21:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:21:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:21:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:21:53 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 0x10c36ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c37c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb500dbad10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c20be00>, '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 0x10c034db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c206b20>, '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.iod25OT5aO/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.o0E7P3bGLA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 13:21:53 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 13:21:53 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 13:21:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iod25OT5aO/Render', 13:21:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:21:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:21:53 INFO - 'HOME': '/Users/cltbld', 13:21:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:21:53 INFO - 'LOGNAME': 'cltbld', 13:21:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:21:53 INFO - 'MOZ_NO_REMOTE': '1', 13:21:53 INFO - 'NO_EM_RESTART': '1', 13:21:53 INFO - 'PAGER': '/bin/cat', 13:21:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:21:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:21:53 INFO - 'PWD': '/builds/slave/test', 13:21:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:21:53 INFO - 'SHELL': '/bin/bash', 13:21:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o0E7P3bGLA/Listeners', 13:21:53 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 13:21:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:21:53 INFO - 'USER': 'cltbld', 13:21:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:21:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:21:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:21:53 INFO - 'XPC_FLAGS': '0x0', 13:21:53 INFO - 'XPC_SERVICE_NAME': '0', 13:21:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:21:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:21:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:21:53 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-3We9TQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:21:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:21:53 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-q4pRAw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:21:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:21:53 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-SL4vew-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:21:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:21:53 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-2ddmtA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:21:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:21:54 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-XqiKtG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:21:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:21:54 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-wNkW6E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:21:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:21:54 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-4PfTNo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:21:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:21:54 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-McUVhq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:21:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:21:54 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-iI87pv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:21:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:21:54 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-bHxSrt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:21:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:21:54 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-JsA4rm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:21:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:21:54 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-cPMC4L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:21:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:21:54 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-Htdl4I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:21:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:21:54 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-DXBv6b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:21:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:21:55 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-JmVs5Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:21:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:21:55 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-cOt97u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:21:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:21:55 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-L92DYo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:21:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:21:55 INFO - Running setup.py install for manifestparser 13:21:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:21:55 INFO - Running setup.py install for mozcrash 13:21:55 INFO - Running setup.py install for mozdebug 13:21:55 INFO - Running setup.py install for mozdevice 13:21:55 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:21:55 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:21:55 INFO - Running setup.py install for mozfile 13:21:56 INFO - Running setup.py install for mozhttpd 13:21:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:21:56 INFO - Running setup.py install for mozinfo 13:21:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:21:56 INFO - Running setup.py install for mozInstall 13:21:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:21:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:21:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:21:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:21:56 INFO - Running setup.py install for mozleak 13:21:56 INFO - Running setup.py install for mozlog 13:21:56 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:21:56 INFO - Running setup.py install for moznetwork 13:21:56 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:21:56 INFO - Running setup.py install for mozprocess 13:21:57 INFO - Running setup.py install for mozprofile 13:21:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:21:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:21:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:21:57 INFO - Running setup.py install for mozrunner 13:21:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:21:57 INFO - Running setup.py install for mozscreenshot 13:21:57 INFO - Running setup.py install for moztest 13:21:57 INFO - Running setup.py install for mozversion 13:21:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:21:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:21:57 INFO - Cleaning up... 13:21:57 INFO - Return code: 0 13:21:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:21:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:21:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:21:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:21:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:21:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:21:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:21:57 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 0x10c36ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c37c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb500dbad10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c20be00>, '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 0x10c034db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c206b20>, '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.iod25OT5aO/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.o0E7P3bGLA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 13:21:57 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 13:21:57 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 13:21:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iod25OT5aO/Render', 13:21:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:21:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:21:57 INFO - 'HOME': '/Users/cltbld', 13:21:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:21:57 INFO - 'LOGNAME': 'cltbld', 13:21:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:21:57 INFO - 'MOZ_NO_REMOTE': '1', 13:21:57 INFO - 'NO_EM_RESTART': '1', 13:21:57 INFO - 'PAGER': '/bin/cat', 13:21:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:21:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:21:57 INFO - 'PWD': '/builds/slave/test', 13:21:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:21:57 INFO - 'SHELL': '/bin/bash', 13:21:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o0E7P3bGLA/Listeners', 13:21:57 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 13:21:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:21:57 INFO - 'USER': 'cltbld', 13:21:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:21:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:21:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:21:57 INFO - 'XPC_FLAGS': '0x0', 13:21:57 INFO - 'XPC_SERVICE_NAME': '0', 13:21:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:21:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:21:58 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-8aqoDZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:21:58 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)) 13:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:21:58 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-ETfupJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:21:58 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)) 13:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:21:58 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-R8AA6m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:21:58 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)) 13:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:21:58 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-Cp8hV0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:21:58 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)) 13:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:21:58 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-h3q5O1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:21:58 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)) 13:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:21:58 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-J4s4xI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:21:58 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)) 13:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:21:58 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-kShq97-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:21:58 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)) 13:21:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:21:58 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-dJRhbO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:21:59 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)) 13:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:21:59 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-T2XgNS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:21:59 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)) 13:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:21:59 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-3xewnh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:21:59 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)) 13:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:21:59 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-zl8Wqn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:21:59 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)) 13:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:21:59 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-_RNchZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:21:59 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)) 13:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:21:59 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-sBH2zv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:21:59 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)) 13:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:21:59 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-Slrmzw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:21:59 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)) 13:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:21:59 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-_oQffP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:21:59 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)) 13:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:21:59 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-m95PE0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:21:59 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)) 13:21:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:21:59 INFO - Running setup.py (path:/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/pip-fxOlwY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:21:59 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)) 13:21:59 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)) 13:21:59 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)) 13:21:59 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)) 13:21:59 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)) 13:21:59 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)) 13:21:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:21:59 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 13:21:59 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 13:21:59 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 13:21:59 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 13:21:59 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 13:21:59 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 13:22:02 INFO - Downloading blessings-1.6.tar.gz 13:22:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:22:02 INFO - Installing collected packages: blessings 13:22:02 INFO - Running setup.py install for blessings 13:22:02 INFO - Successfully installed blessings 13:22:02 INFO - Cleaning up... 13:22:02 INFO - Return code: 0 13:22:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:22:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:22:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:22:03 INFO - Reading from file tmpfile_stdout 13:22:03 INFO - Current package versions: 13:22:03 INFO - blessings == 1.6 13:22:03 INFO - blobuploader == 1.2.4 13:22:03 INFO - docopt == 0.6.1 13:22:03 INFO - manifestparser == 1.1 13:22:03 INFO - mozInstall == 1.12 13:22:03 INFO - mozcrash == 0.17 13:22:03 INFO - mozdebug == 0.1 13:22:03 INFO - mozdevice == 0.48 13:22:03 INFO - mozfile == 1.2 13:22:03 INFO - mozhttpd == 0.7 13:22:03 INFO - mozinfo == 0.9 13:22:03 INFO - mozleak == 0.1 13:22:03 INFO - mozlog == 3.1 13:22:03 INFO - moznetwork == 0.27 13:22:03 INFO - mozprocess == 0.22 13:22:03 INFO - mozprofile == 0.28 13:22:03 INFO - mozrunner == 6.11 13:22:03 INFO - mozscreenshot == 0.1 13:22:03 INFO - mozsystemmonitor == 0.0 13:22:03 INFO - moztest == 0.7 13:22:03 INFO - mozversion == 1.4 13:22:03 INFO - psutil == 3.1.1 13:22:03 INFO - requests == 1.2.3 13:22:03 INFO - wsgiref == 0.1.2 13:22:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:22: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')]} 13:22:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:22:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:22: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')]} 13:22:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:22:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:22:03 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 0x10c36ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c37c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb500dbad10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c20be00>, '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 0x10c034db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c206b20>, '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.iod25OT5aO/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.o0E7P3bGLA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 13:22:03 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 13:22:03 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 13:22:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iod25OT5aO/Render', 13:22:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:22:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:22:03 INFO - 'HOME': '/Users/cltbld', 13:22:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:22:03 INFO - 'LOGNAME': 'cltbld', 13:22:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:22:03 INFO - 'MOZ_NO_REMOTE': '1', 13:22:03 INFO - 'NO_EM_RESTART': '1', 13:22:03 INFO - 'PAGER': '/bin/cat', 13:22:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:22:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:22:03 INFO - 'PWD': '/builds/slave/test', 13:22:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:22:03 INFO - 'SHELL': '/bin/bash', 13:22:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o0E7P3bGLA/Listeners', 13:22:03 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 13:22:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:22:03 INFO - 'USER': 'cltbld', 13:22:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:22:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:22:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:22:03 INFO - 'XPC_FLAGS': '0x0', 13:22:03 INFO - 'XPC_SERVICE_NAME': '0', 13:22:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:22:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:22:03 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)) 13:22:03 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)) 13:22:03 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)) 13:22:03 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)) 13:22:03 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)) 13:22:03 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)) 13:22:03 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)) 13:22:03 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)) 13:22:03 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)) 13:22:03 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)) 13:22:03 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)) 13:22:03 INFO - Cleaning up... 13:22:03 INFO - Return code: 0 13:22:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:22: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')]} 13:22:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:22:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:22: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')]} 13:22:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:22:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:22: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c36ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c37c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb500dbad10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c20be00>, '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 0x10c034db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c206b20>, '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.iod25OT5aO/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.o0E7P3bGLA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 13:22: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', 'jsonschema'] in /builds/slave/test/build 13:22: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 jsonschema 13:22:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iod25OT5aO/Render', 13:22:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:22:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:22:03 INFO - 'HOME': '/Users/cltbld', 13:22:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:22:03 INFO - 'LOGNAME': 'cltbld', 13:22:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:22:03 INFO - 'MOZ_NO_REMOTE': '1', 13:22:03 INFO - 'NO_EM_RESTART': '1', 13:22:03 INFO - 'PAGER': '/bin/cat', 13:22:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:22:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:22:03 INFO - 'PWD': '/builds/slave/test', 13:22:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:22:03 INFO - 'SHELL': '/bin/bash', 13:22:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o0E7P3bGLA/Listeners', 13:22:03 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 13:22:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:22:03 INFO - 'USER': 'cltbld', 13:22:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:22:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:22:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:22:03 INFO - 'XPC_FLAGS': '0x0', 13:22:03 INFO - 'XPC_SERVICE_NAME': '0', 13:22:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:22:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:22:03 INFO - Downloading/unpacking jsonschema 13:22: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 13:22: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 13:22: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 13:22: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 13:22: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 13:22: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 13:22:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:22:05 INFO - Downloading/unpacking functools32 (from jsonschema) 13:22:05 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 13:22:05 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 13:22:05 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 13:22:05 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 13:22:05 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 13:22:05 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 13:22:06 INFO - Downloading functools32-3.2.3-2.tar.gz 13:22:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:22:06 INFO - warning: no files found matching '*.txt' 13:22:06 INFO - no previously-included directories found matching 'build' 13:22:06 INFO - no previously-included directories found matching 'dist' 13:22:06 INFO - no previously-included directories found matching '.git*' 13:22:06 INFO - Installing collected packages: jsonschema, functools32 13:22:06 INFO - Running setup.py install for functools32 13:22:06 INFO - warning: no files found matching '*.txt' 13:22:06 INFO - no previously-included directories found matching 'build' 13:22:06 INFO - no previously-included directories found matching 'dist' 13:22:06 INFO - no previously-included directories found matching '.git*' 13:22:06 INFO - Successfully installed jsonschema functools32 13:22:06 INFO - Cleaning up... 13:22:06 INFO - Return code: 0 13:22:06 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 13:22:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:22:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:22:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:22:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:22:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:22:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:22:06 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 0x10c36ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c37c420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb500dbad10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c20be00>, '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 0x10c034db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c206b20>, '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.iod25OT5aO/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.o0E7P3bGLA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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 13:22:06 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 13:22:06 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 13:22:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iod25OT5aO/Render', 13:22:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:22:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:22:06 INFO - 'HOME': '/Users/cltbld', 13:22:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:22:06 INFO - 'LOGNAME': 'cltbld', 13:22:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:22:06 INFO - 'MOZ_NO_REMOTE': '1', 13:22:06 INFO - 'NO_EM_RESTART': '1', 13:22:06 INFO - 'PAGER': '/bin/cat', 13:22:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:22:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:22:06 INFO - 'PWD': '/builds/slave/test', 13:22:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:22:06 INFO - 'SHELL': '/bin/bash', 13:22:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o0E7P3bGLA/Listeners', 13:22:06 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 13:22:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:22:06 INFO - 'USER': 'cltbld', 13:22:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:22:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:22:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:22:06 INFO - 'XPC_FLAGS': '0x0', 13:22:06 INFO - 'XPC_SERVICE_NAME': '0', 13:22:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:22:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:22:06 INFO - Downloading/unpacking flake8 13:22:06 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 13:22:06 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 13:22:06 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 13:22:06 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 13:22:06 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 13:22:06 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 13:22:09 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 13:22:09 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 13:22:09 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 13:22:09 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 13:22:09 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 13:22:09 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 13:22:09 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 13:22:09 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 13:22:09 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 13:22:09 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 13:22:09 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 13:22:09 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 13:22:09 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 13:22:09 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 13:22:09 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 13:22:09 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 13:22:10 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 13:22:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:22:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:22:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:22:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:22:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:22:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:22:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 13:22:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:22:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:22:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:22:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:22:10 INFO - no previously-included directories found matching 'docs/_build' 13:22:10 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 13:22:10 INFO - Running setup.py install for pep8 13:22:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:22:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:22:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:22:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:22:10 INFO - no previously-included directories found matching 'docs/_build' 13:22:10 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 13:22:10 INFO - Successfully installed flake8 mccabe pyflakes pep8 13:22:10 INFO - Cleaning up... 13:22:11 INFO - Return code: 0 13:22:11 INFO - Running post-action listener: _resource_record_post_action 13:22:11 INFO - Running post-action listener: _start_resource_monitoring 13:22:11 INFO - Starting resource monitoring. 13:22:11 INFO - ##### 13:22:11 INFO - ##### Running install step. 13:22:11 INFO - ##### 13:22:11 INFO - Running pre-action listener: _resource_record_pre_action 13:22:11 INFO - Running main action method: install 13:22:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:22:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:22:11 INFO - Reading from file tmpfile_stdout 13:22:11 INFO - Detecting whether we're running mozinstall >=1.0... 13:22:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:22:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:22:11 INFO - Reading from file tmpfile_stdout 13:22:11 INFO - Output received: 13:22:11 INFO - Usage: mozinstall [options] installer 13:22:11 INFO - Options: 13:22:11 INFO - -h, --help show this help message and exit 13:22:11 INFO - -d DEST, --destination=DEST 13:22:11 INFO - Directory to install application into. [default: 13:22:11 INFO - "/builds/slave/test"] 13:22:11 INFO - --app=APP Application being installed. [default: firefox] 13:22:11 INFO - mkdir: /builds/slave/test/build/application 13:22:11 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'] 13:22:11 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 13:22:45 INFO - Reading from file tmpfile_stdout 13:22:45 INFO - Output received: 13:22:45 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 13:22:45 INFO - Running post-action listener: _resource_record_post_action 13:22:45 INFO - ##### 13:22:45 INFO - ##### Running run-tests step. 13:22:45 INFO - ##### 13:22:45 INFO - Running pre-action listener: _resource_record_pre_action 13:22:45 INFO - Running main action method: run_tests 13:22:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:22:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:22:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:22:45 INFO - 2016-04-15 13:22:45.032 screenresolution[1641:7654] starting screenresolution argv=screenresolution get 13:22:45 INFO - 2016-04-15 13:22:45.035 screenresolution[1641:7654] Display 0: 1600x1200x32@60 13:22:45 INFO - 2016-04-15 13:22:45.041 screenresolution[1642:7656] starting screenresolution argv=screenresolution list 13:22:45 INFO - Available Modes on Display 0 13:22:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:22:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:22:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:22:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:22:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:22:45 INFO - Intel Iris: 13:22:45 INFO - Chipset Model: Intel Iris 13:22:45 INFO - Type: GPU 13:22:45 INFO - Bus: Built-In 13:22:45 INFO - VRAM (Dynamic, Max): 1536 MB 13:22:45 INFO - Vendor: Intel (0x8086) 13:22:45 INFO - Device ID: 0x0a2e 13:22:45 INFO - Revision ID: 0x0009 13:22:45 INFO - Displays: 13:22:45 INFO - Display: 13:22:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:22:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:22:45 INFO - Display Serial Number: 1600x1200 60 13:22:45 INFO - Main Display: Yes 13:22:45 INFO - Mirror: Off 13:22:45 INFO - Online: Yes 13:22:45 INFO - Rotation: Supported 13:22:45 INFO - Return code: 0 13:22:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:22:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:22:45 INFO - Python 2.7.3 13:22:45 INFO - Return code: 0 13:22:45 INFO - grabbing minidump binary from tooltool 13:22: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')]} 13:22:45 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 0x10c20be00>, '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 0x10c034db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c206b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:22:45 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 13:22:45 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 13:22:45 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:22:45 INFO - Return code: 0 13:22:45 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:22:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:22:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:22:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:22:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:22:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 13:22:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 13:22:46 INFO - Return code: 0 13:22:46 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/ekxJUFGQRouhYelxehikiQ/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0029', '--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 13:22:46 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/ekxJUFGQRouhYelxehikiQ/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0029 --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 13:22:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iod25OT5aO/Render', 13:22:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:22:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:22:46 INFO - 'HOME': '/Users/cltbld', 13:22:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:22:46 INFO - 'LOGNAME': 'cltbld', 13:22:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:22:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:22:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:22:46 INFO - 'MOZ_NO_REMOTE': '1', 13:22:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:22:46 INFO - 'NO_EM_RESTART': '1', 13:22:46 INFO - 'PAGER': '/bin/cat', 13:22:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:22:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:22:46 INFO - 'PWD': '/builds/slave/test', 13:22:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:22:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:22:46 INFO - 'SHELL': '/bin/bash', 13:22:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.o0E7P3bGLA/Listeners', 13:22:46 INFO - 'TMPDIR': '/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/', 13:22:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:22:46 INFO - 'USER': 'cltbld', 13:22:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:22:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:22:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:22:46 INFO - 'XPC_FLAGS': '0x0', 13:22:46 INFO - 'XPC_SERVICE_NAME': '0', 13:22:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:22:46 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/ekxJUFGQRouhYelxehikiQ/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0029', '--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 13:22:46 INFO - mozversion application_buildid: 20160415122040 13:22:46 INFO - mozversion application_changeset: 355e9b83a662dd7e90a73b7c9cd556372c063ae9 13:22:46 INFO - mozversion application_display_name: Nightly 13:22:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:22:46 INFO - mozversion application_name: Firefox 13:22:46 INFO - mozversion application_remotingname: firefox 13:22:46 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 13:22:46 INFO - mozversion application_vendor: Mozilla 13:22:46 INFO - mozversion application_version: 48.0a1 13:22:46 INFO - mozversion platform_buildid: 20160415122040 13:22:46 INFO - mozversion platform_changeset: 355e9b83a662dd7e90a73b7c9cd556372c063ae9 13:22:46 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 13:22:46 INFO - mozversion platform_version: 48.0a1 13:22:46 INFO - using testdate: 1460751766 13:22:46 INFO - actual date: 1460751766 13:22:46 INFO - starting webserver on 'localhost:49275' 13:22:46 INFO - SUITE-START | Running 2 tests 13:22:46 INFO - TEST-START | dromaeo_css 13:22:46 INFO - operating with platform_type : mac_ 13:22:46 INFO - Initialising browser for dromaeo_css test... 13:22:46 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpwlYJUw/profile http://localhost:49275/getInfo.html) 13:22:49 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 13:22:49 INFO - PROCESS | 1660 | colorDepth:24 13:22:49 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 13:22:49 INFO - PROCESS | 1660 | __metrics 13:22:49 INFO - PROCESS | 1660 | 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" 13:22:49 ERROR - PROCESS | 1660 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 13:22:50 INFO - TEST-INFO | 1660: exit 0 13:22:50 INFO - Browser initialized. 13:22:50 INFO - Running cycle 1/1 for dromaeo_css test... 13:22:50 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpwlYJUw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:23:02 INFO - PROCESS | 1662 | RSS: Main: 238821376 13:23:02 INFO - PROCESS | 1662 | 13:23:53 INFO - PROCESS | 1662 | 1460751833695 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] 13:26:04 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 13:26:05 INFO - PROCESS | 1662 | RSS: Main: 377200640 13:26:05 INFO - PROCESS | 1662 | 13:29:08 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 13:29:09 INFO - PROCESS | 1662 | RSS: Main: 379932672 13:29:09 INFO - PROCESS | 1662 | 13:32:11 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 13:32:12 INFO - PROCESS | 1662 | RSS: Main: 377061376 13:32:12 INFO - PROCESS | 1662 | 13:35:14 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 13:35:14 INFO - PROCESS | 1662 | RSS: Main: 374276096 13:35:14 INFO - PROCESS | 1662 | 13:38:17 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 13:38:18 INFO - PROCESS | 1662 | RSS: Main: 345509888 13:38:18 INFO - PROCESS | 1662 | 13:41:21 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 13:41:21 INFO - PROCESS | 1662 | RSS: Main: 400228352 13:41:21 INFO - PROCESS | 1662 | 13:41:21 INFO - PROCESS | 1662 | __start_tp_report 13:41:21 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 13:41:21 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 13:41:21 INFO - PROCESS | 1662 | |i|pagename|runs| 13:41:21 INFO - PROCESS | 1662 | |0;dojo.html;3394.100563350712;3472.1666799967998;3488.2849015951724;3502.2120022994845;3537.964620353796;5089.567386772124;5394.676319420832;5599.384067752544;5700.458456446641;5735.397783232763;5697.6866272355;5706.65760054397;5757.913631295523;5847.4152584741505;5856.912146317798;5137.614678899079;5173.405058418273;5221.712805795699;5235.397929238148;5322.414534401213;5282.339707536558;5291.841244762644;5340.62615616905;5347.331583552056;5387.568994480433;5135.948640513603;5152.536271735562;5216.634835561512;5269.367675878881;5284.180951952453;5205.089109405877;5220.843374698765;5222.3733152022005;5230.947690523122;5233.502817232356;5190.792368305263;5198.818041368571;5217.3217481727515;5255.395629502618;5261;5515.227868098469;5590.972045139789;5626.184203290541;5644.379118296984;5690.573207009491;6231.40801623845;6246.219222597176;6260.874782504325;6272;6277.152584401093;4845.83039593616;6298.2756982947085;6300.180976473029;6309.526785491106;6314.526410519184;5679.460451257124;5774.884502309972;5793.8841223175305;5805.854853628651;5897.67562784051;6276.6547839869245;6305.842353941096;6309.621422714652;6313.747450101944;6334.176557047646;6151.72317245716;6197.783077592353;6218.9427797273665;6218.968905155445;6222.315545290015;6102.246889800143;6207.751689932443;6316.652584107826;6332.746690132343;6334.334894835976;6195.287541932736;6236.594621349598;6237.189165408558;6240.781572644935;6245.219347581552;6246.4378205960775;6254.437100660963;6254.468370188494;6264.185655864709;6280.403361680633;4744.335792988984;4771.475137734847;4837.443693975168;4838.806447742121;4840.99999999993;4443;4457.866264012085;4488.3940668010055;4489.169503641837;4497.9325310120375;5628.015097358028;5636.492715655529;5649.067903795828;5659.207710920475;5663.66018038919;6181.629102253879;6184.381561843779;6250.874982500324;6261.092141639528;6290.182276304051;5641.746121424546;5665.405132461115;5673.744681489343;5677.602567820296;5697.173909783059;5392.622516423891;5400.756965936543;5409.891802163935;5410.594205434608;5416.783328666809;6189.226346706661;6332.873342533216;6356.4279214870885;6381.074744162071;6423.839404014937;6257.4994000480765;6275.090111933835;6302.968485157545;6313.494920406449;6317.5893566918;6345.619262844244;6375.808725738235;6400.55196136266;6401.231852177769;6409.391107844747;6080.817575472743;6109.526129009804;6147.84630384244;6219.315875253809;6227.377262273736;6256.186695729526;6279.466245369194;6280.9685951569945;6292.2134733158355;6298.71655775482;5954.791582294599;6018.067199584071;6112.602680825732;6137.110119032626;6144;6042.395760423922;6110.0424004731585;6113.233070740914;6121.666376154749;6135.07973803933;5642.407547207484;5883.294004719461;5923.348431672426;5926.377730338252;5951.970240148945;4707.646926480459;5855.385184555731;6130.6010765251895;6131.07927964819;6139.6009259398;5578.940001399721;5864.736086876186;5877.500412464989;5886.705664716661;5886.911696324473 13:41:21 INFO - PROCESS | 1662 | |1;ext.html;509.2615707224523;522.4957915611435;575.4015823543514;599.0804115682429;620.2433031701323;25049.373765655866;30561.69438305616;37889.43165852514;38322.808385958066;39354.606453935456;41071;41787.58212417875;41893;41900.37149442761;41936.79031604838;9402;9614.903850961471;9619.56711947964;9626.759331016745;9639.939606643264;8635.43869648474;10430.061294483496;10579.153667706565;11635.127365447583;11965.641030769091;11504.654860354161;11610.36143012134;11642.534298628045;11648.708782280468;11662.47518861649;11583.594574189905;11736.061115110857;11747.823782643181;11752.294862308288;11765.470488836481;11494.13793965456;11601.303921764722;11621.186516943817;11625.069994400428;11827.586034488751;39856.80429587117;40284;40351;40361.39457908134;40507.7974610128;3305.5206994985833;3388.7119594834467;3544;3622.963770362289;3785.962140378616;28822.423551528853;28960.042233810025;29036;29296.8535157325;29497.70502294971;2687.4087700705813;2704.445588654341;2712.864356782153;2837.205582436921;2920.5765164051095;439775;441231;442352;442452;442937.7853110746;62487.6875615619;62843;62900.37099628945;63142.36857631457;63268.68365658142;54790.72604636951;65679;65856.68286634337;66103.66948165324;66111.33888661148;15077;16784.748228776578;17018.319267229173;17030.914845425694;17041.147942603067;11699.532018719156;11788.46951887167;11791.410429478492;11791.587294444653;12798.23210607348;13915.58253252404;17410.129493525525;17435.302587896596;17864.285428582712;17870.731939020927;6146.877062458726;7510.286522780327;7535.60897596073;7565.962170189114;7617.847643047219;978.2858513285341;989.2877128467492;1083.1443159903588;1083.9620613278498;1099.8240281554913;29195.854020730185;29251.85374073116;29787.70212297849;29906.40187196244;30116.69883301183;1084.2952081147316;1097.983530247047;1108.6729414822646;1155.7688462307572;1157.8375739217668;14925.17911514877;15122.39510419593;15307.693846123238;15470.994385364915;15583.06501609907;541447;546885;549857;549939;551530;58485.83028339409;58871.70564147152;58908.11637825436;58964.70517647384;59037;1426.6290764401124;1443.6246575890134;1457.4316016753687;1470.9190994495405;1482.918439485817;28361.72372243213;28780.11468451732;28996.42007159803;29056.854715726287;29610;49059;49341.75329123331;49854;49870.50129498804;49908.75228119326;369419;370112.2988770078;372015;373486;373933;35253.29493410169;39010;39268.60731392649;40758.592414076666;41560.79219603883;41696.79151604223;42564.36153457639;43088.569114308455;43359.56640433555;43872.78063609661;35936.82031589825;37678.24643507169;38331.616683832806;39378;39903.40144897887;35216.94349169529;35338.64661353456;35671.92984210478;36118.81940590385;36211.27577448489;35154;35224;35226.76706315266;36512.26975460529;36577.63422365849;30581.847090764404;31606.683933160373;31720;31726.730930763435;31884;23625;24198.63702044506;24255;24844.50310993806;25355.6196657054 13:41:21 INFO - PROCESS | 1662 | |2;jquery.html;392.960703929607;393.1566789237086;394.50686641697877;396.44299758838844;405.1774896959172;14139.872601804795;15695.921520392381;19079.713804292947;19893.3037343693;19929;16238.594035149126;18483;19904;20163.19347226113;20519.17923283067;5659.462351076651;5823.06830907055;5863.2670916135485;5872.911906321408;5874.5006674432725;5663.546916246691;5854.941450585482;5874.236349274588;5897.380775018625;6063.9090413643835;3918.921621567581;4153;5794.275715535558;6042.879142417149;6131.172291740625;6034;6094.573379863411;6112.358202388752;6123.357047510036;6124.448799608012;4676.532346765331;5745.368009518991;5779.88440231193;5781.82654520365;5790.971045144789;10545.630902918361;12764.87235127646;12953.352332383438;12985.870141298561;13284.202947823065;1948.2499237793552;2164.9567008659897;2213.269621025058;2259.2610910529766;2751.0783887397747;7522.699092036312;9522.190613797835;9996.70009899697;10857.837132443019;14763.818894488415;1592.8884978051542;1638.6313079557124;1684.6378028723884;1686.5405496946664;1711.1273250642137;113964.43017784941;120712.99999999913;120804;121341;121576.39211803973;11391.544338226544;11537;11573.826392604118;11615.593454229062;11730.354830484406;14623.488177913721;14775.18736469483;14786.334614942138;14791.92604036973;14812.777808332668;8418.747437576883;8510.234078932803;8515.21180552091;8516.744497664957;8615.612297446505;6676.399124078759;6736.696848641823;7019.4384449243935;8450.281726053354;8468;3708.554973403155;3843;4472.798724057425;4537.500874903758;4596.931046034312;1748.094576911173;1880.7366968624403;1929.199382256348;1933.9983117473348;1942.3298961858136;871.808202195516;913.9268858491438;925.3892430995511;979.6179489998906;988.6391467114452;19402.96279340537;20034.298799542237;20197.899010504854;20293.18827246923;20338.288159914333;928.7120992492349;931.6320053578798;956.383132879289;986.0632399220625;992.7964767222702;10354.896451035393;10633.46832658364;10647.787044259227;10649.520771565298;10685.252032357816;578759;589532;596367;599438;600737;119166;122493.38753306176;131271.3436432831;132676.33661831822;133069;450.6845208354165;461.42552522110185;467.4320700349092;468.73516463198183;469.4648101164607;8629.654813807378;10508.896565860474;10671.306365086244;10680.092192163442;10873.45632718345;33405.99782006544;48977.755111224214;49694.50305496899;50034.49965500444;50515;74699;75911.62044189744;77017.22982770325;77070.22929770855;77944;7531.924680753123;7824.295813376823;7934.404919631166;8090.271875531231;8172.509649421054;11889.583864564698;11939.582114626293;12088.637340879788;12135.453904574491;12398.876011239772;10294.970502949645;10558.788824223626;10663.200259980378;10736.62421815264;10800.641070308928;10153.441560714231;10338.586456541654;10447.68656940293;10554.261201716114;10650;10463.372197668165;10767.676969690921;11050.834237486604;11141.944290278496;11169.441527923735;7832.882506762289;7908.723194688158;8046.396520260887;8101.675932962616;8292.958535207285;9183.954080229556;10593.205509586658;10614.575416983234;10727.731806704895;10912.836307455553 13:41:21 INFO - PROCESS | 1662 | |3;mootools.html;1423.1176670464301;1572.606848287928;1603.5991002249439;1842.9447116586498;1977.9901100494496;7750.883736743946;8032.558209298486;8035.3571714262935;8570.057293697688;8790.208881200691;8455.365847561427;8543.316534677226;8629.65481380744;8639.222469977702;8669.69656062038;2403.0628055058496;2437.5612389769835;2438.9512209755794;2439;2439.780419762221;2390.1634427950253;2416.867072311022;2433.4038160650666;2439.6706444630013;2441.0479912834007;2425.8908349124245;2431.5623187826186;2439.3047981325326;2440.1093600835743;2445.1686426614947;2373.6439534069855;2394.9640755388596;2405.506871091422;2406.6750988616486;2411.2043025801618;2367.1596583213054;2401.5076909233567;2405.579023670868;2409.0123049549775;2412.9396765080837;8601.655933762642;8609.483430994098;8664.653413863438;8674.132586741276;8695.304375649934;5209.921851172236;5351.652142610717;5366.70483123428;5395;5412.404635490093;3453.620101788801;3492;3561.554805649294;3566.197605538759;3580.964190358115;1016.7458135466134;1032.359936839157;1034.4775888176498;1041;1046.1944302886811;11422.428878556038;11569.42152892352;11571.94214028933;11584.30494170344;11598.826017609743;4434.17967675981;4463.598276155161;4658.254679251371;4677.64917631179;4680.625549955996;9054.320925930582;9060.54697265134;9075.863862042075;9091.31815113869;9096.681616143404;5237.685738855681;5238.240455133984;5241.9213711794355;5268.789248430096;5270.235815806686;4033.2740106780475;4387.5393083726385;4501.3022981437925;4752;4900.191468407744;3465.2722928184858;3734.9626503735158;3820.8471661133244;3856.768593884376;3901.0637447012514;1041.770810421706;1124.9043831274269;1209.661294837447;1210.9939450302868;1228.121892846619;901.1034021148868;902.273669695901;911.2709832134399;935.5135329628555;948.8054948735493;5699.204750503661;5867.794627188028;5868.823935281948;5978.432048955342;6035.231298966944;430.79537219820963;452.2492662180764;459.3431393107821;463.46932761987335;469.6078774223493;3847.788371639587;3910.2570511602703;3918.686505079587;3969.3450580653866;3989.92020159601;10214.591416343412;10613.257072004886;10776.461176941122;10782.353058816496;10791.56833726642;10710;10714.46427678613;10720.410377429316;10721;10735.463226838627;7799.688012479551;8007.679692812222;8154.714584989379;8184.2224988625985;8201.42590018693;5699.230603868425;5836.883262334644;5855.531557475477;5882.941170588239;5897.3807750185615;9587.952060239653;9763.218942484442;9814.803703926023;9960.900390995997;10051.296409251283;9727.416355018722;9948.651797187063;10024.749381265527;10098.141657959155;10132.696019119438;6291.559590828598;6667.599944003376;6911.343422374866;6920.377166055079;6921.169459664872;8244.876326854983;8251.587420629065;8334.916650833415;8337.041240257206;8345.499270043816;6111.96944015277;7086.645667716697;7225.674844631899;7332.486725929347;7402.259774022555;7191.7842464726145;7197.532160409557;7208;7285.344319011315;7318.487705860539;6774.762883299061;6821.147356580427;6846.623435711083;6857.691403886937;6861.965690171517;3994.5805690403254;4741.075490279362;4788;4856.1016212000895;4930.433000205022;4853.611711063177;4874.731889746098;4956.430010548689;4959.156943319572;5002.674826136289 13:41:21 INFO - PROCESS | 1662 | |4;prototype.html;304.50973931969526;313.62835040477034;322.4856354115186;325.3168346472407;325.784981911938;5407.648087978006;5724.055530837407;5949.720810025844;6043.18417013703;6063.939360606392;4344.978275108628;5236.031334203175;5643.379228284885;5909.079549146534;6020.247469066367;4042.959570404302;4469.53069927657;4472.865814025568;4479.910401791963;4531.750753708545;4168.312228482297;4445.777711114448;4468.865934021985;4503.662225333097;4512.458504979407;4343.1747967886;4377.912441751163;4384.254676704968;4386.97806510967;4405.515393306734;4387.517373088974;4407.625351845097;4414.536473670282;4416.536263692298;4426.601605855489;3640.890773276806;3757.079515518688;3788.8484460621544;3803.7717736935874;3817.0457385653585;4743.478217396084;5598.1322894951345;5669.489745922846;5700.714964251771;5708.372079071299;5022.221555658879;5032.672876263068;5052.1411360068505;5052.166392545226;5053.84838454847;4998.850034498971;5326;6018.428249316352;6068.362821903681;6204.224471941007;3781.5084039074745;4142.544320124784;4159.880089932551;4160.45914031174;4173.373993900909;5519.420460851593;5779.942200578024;6572.277049524549;6656.93343066568;6689.33106689332;3942.9605703943166;3992.4647848616337;4208.453885399351;4231;4260.573942605776;3965.167314863911;3983.820728067244;4249.001484651105;4320.308750599889;4339.934900976488;5050.4697006814495;5061.519155680205;5065.493450654905;5080.044182989575;5095.745212739423;6048.727807248596;6321.273053598803;6355.110284560073;6380.680965951684;6410.903836442457;1209.7520008398258;1463.5389852196738;1487.5983484459136;1490.3070072416378;1497.101738956639;950.7290422229631;961.6201600367815;966.5457235099492;970.7039352997263;977.9657711980188;4910.386201724785;5791.797287095016;5869.970650146722;5892.793752218652;5913.438223368773;6445.355464453517;6466.805995820133;6472.838179045468;6479.384458476437;6481.606454612281;5190.013897359565;5214.45248248936;5578.24693666358;5905.379935106838;5974.3726908674835;3304.884329048472;3464.2378676691087;3482.1294676330917;3487.3722729908354;3488.930221395558;6566.4418524425;6779.406839392739;6814.1482314710665;6820.147481564804;6847.897281540781;5857.15071314657;6182.69086545671;6718.260991290954;6720.260771315151;6758;5953.910691339634;5960.314563825215;6200.80658460893;6346.17499725042;6462.4183823456115;5684.914726279192;5954.791582294599;6311.589746666452;6515.729432760756;6549.934500655123;6415.518836087405;6426.518011149089;6448.000559913221;6455.870882582416;6465.030245463218;6472.708728107153;6507.869842603216;6790.8641827164165;6824.488163387865;6846.212685541226;3953.486046814011;4081.40819581165;4175.060611362419;4261.595148460891;4265.274903266514;5211.60912931524;5917.881642367042;6135.447809697149;6512.6092434454085;6609.20689517261;6055.637481566612;6251.843703907439;6322.49420046386;6331.176946996861;6333.334999825136;5603.495685388335;5945.464908158287;6305.621662700252;6310.1796766420075;6316.873662526816;5902.321233058082;6193.473554747897;6203.937960620336;6207.348228435949;6231.688415579293;5284.920726189033;6323.747050117944;6333.1766870306565;6345.555811093179;6346.2067241594805;6209.441150296314;6219.160413344141;6219.689015549295;6226.315105338319;6261.68691565429 13:41:21 INFO - PROCESS | 1662 | |5;yui.html;579.3920338538964;727.9344858962695;734.3794493652865;734.5959722152814;785.3677789379551;13649.590512284623;14314;14805.555833325005;15279.465218717332;15299.617509562253;13882;15500.612484687861;15543.22283885804;15573.29920153593;15583.610409739735;879.6789171952239;884.8849649545582;887.427609192074;888.693400776731;889.297455010541;726.1213931143322;862.7584276402617;884.7832281091141;892.8616064509979;895.6999405199266;908.9636414543444;939.5724945149974;941.5857022909931;942.3074040580168;942.4062840410531;925.0194793518891;930.3069213435999;932.4545141092469;932.7574830544107;932.9440233586007;691.989620155698;693.774523279931;693.8404167041558;694.8262934266434;695.6591270277578;14688.48590299345;14760.704785904329;14790.556283311518;15054.397824087024;15122;12292;12297.262164270172;12308.630741077783;12528.436220370106;12539.122261441702;9702.417854928726;10048.799024019552;10110.69667909964;10122.746931326703;10344.586216551328;3056.66376698563;3442.707369873564;3461.463473161639;3497.4578940264046;3722.181120153562;71405.57188856194;81445.59277203635;81459;84728.15271847325;85066.72399913945;9323.90676093244;9328.300377471716;9380;9447.90552094484;9805.656802011896;20694.586108277752;20790.58418831615;20831;20836.47908802292;20852.687209691867;7168.964155179191;7203.567785932861;7372.96313518429;7469.813254668677;7659.310662040443;3726.16161363691;4021.8592349267633;4057.188562287495;4057.695672824491;4059.4925634295714;3368.3263347330144;3495.8426870790877;3516.40221162403;3580.838862251205;3748.8125593720206;676;679.0832376291967;681.5229339462396;694.8540806430706;699.4823830365493;494.31537320810935;545.5308434746115;556.0630337880684;570.0167211560108;602.0367412140628;7543.207963163899;7592.620368981528;7600;7824.882626760603;8153.55155466455;552.5993654600463;556.8914061757919;562.9549636029108;574.681052016135;580.4746704232676;3740.214554943438;3744.981275093607;3809.3524100902914;3847.345951188249;3935.252302062599;87747.56126219328;88204;89663;90055.54972225097;94075;76492.61753691196;76965;77410;78660;79487;67866;68691;70447.2955270451;70918;71939.2806071943;7331.560106393634;7388;7452.925470745223;7649.579273140031;7807.375409967302;43361.78319108384;45008;45385;45411.545884540734;45496.31755523736;65107.02339464817;65759.34240657724;66256;66348.36038936744;66574.66712666406;9544.761380965254;9953;10825.971532704381;11003.779924401628;11057.83413248818;11649.650510484698;12087.335196564274;12333.93833030829;12577.182483138567;12609.43257553394;11188.832167517656;11213;11252.26860254081;11420.600278990196;11665.766684666089;11025.779484410426;11039;11324.32054076758;11401.657950261506;11777.234479758788;11547.133964952494;11665.59170429031;11707.707307317385;11768.470418831002;11792.76414471723;7772.4947878389985;8235.176482351717;9602.95198524003;9719.65981190655;9721.805563888824;9044;9182.091503915472;9257.953710231406;9544.141027307576;9549 13:41:21 INFO - PROCESS | 1662 | __end_tp_report 13:41:21 INFO - PROCESS | 1662 | __start_cc_report 13:41:21 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,1333 13:41:21 INFO - PROCESS | 1662 | __end_cc_report 13:41:21 INFO - PROCESS | 1662 | __startTimestamp1460752881858__endTimestamp 13:41:21 INFO - PROCESS | 1662 | 13:41:21 INFO - PROCESS | 1662 | ------- Summary: start ------- 13:41:21 INFO - PROCESS | 1662 | Number of tests: 6 13:41:21 INFO - PROCESS | 1662 | 13:41:21 INFO - PROCESS | 1662 | [#0] dojo.html Cycles:165 Average:5734.79 Median:5886.71 stddev:642.57 (10.9%) stddev-sans-first:617.75 13:41:21 INFO - PROCESS | 1662 | Values: 3394.1 3472.2 3488.3 3502.2 3538.0 5089.6 5394.7 5599.4 5700.5 5735.4 5697.7 5706.7 5757.9 5847.4 5856.9 5137.6 5173.4 5221.7 5235.4 5322.4 5282.3 5291.8 5340.6 5347.3 5387.6 5135.9 5152.5 5216.6 5269.4 5284.2 5205.1 5220.8 5222.4 5230.9 5233.5 5190.8 5198.8 5217.3 5255.4 5261.0 5515.2 5591.0 5626.2 5644.4 5690.6 6231.4 6246.2 6260.9 6272.0 6277.2 4845.8 6298.3 6300.2 6309.5 6314.5 5679.5 5774.9 5793.9 5805.9 5897.7 6276.7 6305.8 6309.6 6313.7 6334.2 6151.7 6197.8 6218.9 6219.0 6222.3 6102.2 6207.8 6316.7 6332.7 6334.3 6195.3 6236.6 6237.2 6240.8 6245.2 6246.4 6254.4 6254.5 6264.2 6280.4 4744.3 4771.5 4837.4 4838.8 4841.0 4443.0 4457.9 4488.4 4489.2 4497.9 5628.0 5636.5 5649.1 5659.2 5663.7 6181.6 6184.4 6250.9 6261.1 6290.2 5641.7 5665.4 5673.7 5677.6 5697.2 5392.6 5400.8 5409.9 5410.6 5416.8 6189.2 6332.9 6356.4 6381.1 6423.8 6257.5 6275.1 6303.0 6313.5 6317.6 6345.6 6375.8 6400.6 6401.2 6409.4 6080.8 6109.5 6147.8 6219.3 6227.4 6256.2 6279.5 6281.0 6292.2 6298.7 5954.8 6018.1 6112.6 6137.1 6144.0 6042.4 6110.0 6113.2 6121.7 6135.1 5642.4 5883.3 5923.3 5926.4 5952.0 4707.6 5855.4 6130.6 6131.1 6139.6 5578.9 5864.7 5877.5 5886.7 5886.9 13:41:21 INFO - PROCESS | 1662 | 13:41:21 INFO - PROCESS | 1662 | [#1] ext.html Cycles:180 Average:60610.58 Median:35295.97 stddev:122027.40 (345.7%) stddev-sans-first:122286.29 13:41:21 INFO - PROCESS | 1662 | Values: 509.3 522.5 575.4 599.1 620.2 25049.4 30561.7 37889.4 38322.8 39354.6 41071.0 41787.6 41893.0 41900.4 41936.8 9402.0 9614.9 9619.6 9626.8 9639.9 8635.4 10430.1 10579.2 11635.1 11965.6 11504.7 11610.4 11642.5 11648.7 11662.5 11583.6 11736.1 11747.8 11752.3 11765.5 11494.1 11601.3 11621.2 11625.1 11827.6 39856.8 40284.0 40351.0 40361.4 40507.8 3305.5 3388.7 3544.0 3623.0 3786.0 28822.4 28960.0 29036.0 29296.9 29497.7 2687.4 2704.4 2712.9 2837.2 2920.6 439775.0 441231.0 442352.0 442452.0 442937.8 62487.7 62843.0 62900.4 63142.4 63268.7 54790.7 65679.0 65856.7 66103.7 66111.3 15077.0 16784.7 17018.3 17030.9 17041.1 11699.5 11788.5 11791.4 11791.6 12798.2 13915.6 17410.1 17435.3 17864.3 17870.7 6146.9 7510.3 7535.6 7566.0 7617.8 978.3 989.3 1083.1 1084.0 1099.8 29195.9 29251.9 29787.7 29906.4 30116.7 1084.3 1098.0 1108.7 1155.8 1157.8 14925.2 15122.4 15307.7 15471.0 15583.1 541447.0 546885.0 549857.0 549939.0 551530.0 58485.8 58871.7 58908.1 58964.7 59037.0 1426.6 1443.6 1457.4 1470.9 1482.9 28361.7 28780.1 28996.4 29056.9 29610.0 49059.0 49341.8 49854.0 49870.5 49908.8 369419.0 370112.3 372015.0 373486.0 373933.0 35253.3 39010.0 39268.6 40758.6 41560.8 41696.8 42564.4 43088.6 43359.6 43872.8 35936.8 37678.2 38331.6 39378.0 39903.4 35216.9 35338.6 35671.9 36118.8 36211.3 35154.0 35224.0 35226.8 36512.3 36577.6 30581.8 31606.7 31720.0 31726.7 31884.0 23625.0 24198.6 24255.0 24844.5 25355.6 13:41:21 INFO - PROCESS | 1662 | 13:41:21 INFO - PROCESS | 1662 | [#2] jquery.html Cycles:180 Average:33840.94 Median:18557.28 stddev:99183.15 (534.5%) stddev-sans-first:99429.59 13:41:21 INFO - PROCESS | 1662 | Values: 393.0 393.2 394.5 396.4 405.2 14139.9 15695.9 19079.7 19893.3 19929.0 16238.6 18483.0 19904.0 20163.2 20519.2 5659.5 5823.1 5863.3 5872.9 5874.5 5663.5 5854.9 5874.2 5897.4 6063.9 3918.9 4153.0 5794.3 6042.9 6131.2 6034.0 6094.6 6112.4 6123.4 6124.4 4676.5 5745.4 5779.9 5781.8 5791.0 10545.6 12764.9 12953.4 12985.9 13284.2 1948.2 2165.0 2213.3 2259.3 2751.1 7522.7 9522.2 9996.7 10857.8 14763.8 1592.9 1638.6 1684.6 1686.5 1711.1 113964.4 120713.0 120804.0 121341.0 121576.4 11391.5 11537.0 11573.8 11615.6 11730.4 14623.5 14775.2 14786.3 14791.9 14812.8 8418.7 8510.2 8515.2 8516.7 8615.6 6676.4 6736.7 7019.4 8450.3 8468.0 3708.6 3843.0 4472.8 4537.5 4596.9 1748.1 1880.7 1929.2 1934.0 1942.3 871.8 913.9 925.4 979.6 988.6 19403.0 20034.3 20197.9 20293.2 20338.3 928.7 931.6 956.4 986.1 992.8 10354.9 10633.5 10647.8 10649.5 10685.3 578759.0 589532.0 596367.0 599438.0 600737.0 119166.0 122493.4 131271.3 132676.3 133069.0 450.7 461.4 467.4 468.7 469.5 8629.7 10508.9 10671.3 10680.1 10873.5 33406.0 48977.8 49694.5 50034.5 50515.0 74699.0 75911.6 77017.2 77070.2 77944.0 7531.9 7824.3 7934.4 8090.3 8172.5 11889.6 11939.6 12088.6 12135.5 12398.9 10295.0 10558.8 10663.2 10736.6 10800.6 10153.4 10338.6 10447.7 10554.3 10650.0 10463.4 10767.7 11050.8 11141.9 11169.4 7832.9 7908.7 8046.4 8101.7 8293.0 9184.0 10593.2 10614.6 10727.7 10912.8 13:41:21 INFO - PROCESS | 1662 | 13:41:21 INFO - PROCESS | 1662 | [#3] mootools.html Cycles:180 Average:5585.18 Median:4820.81 stddev:3137.73 (65.1%) stddev-sans-first:3130.94 13:41:21 INFO - PROCESS | 1662 | Values: 1423.1 1572.6 1603.6 1842.9 1978.0 7750.9 8032.6 8035.4 8570.1 8790.2 8455.4 8543.3 8629.7 8639.2 8669.7 2403.1 2437.6 2439.0 2439.0 2439.8 2390.2 2416.9 2433.4 2439.7 2441.0 2425.9 2431.6 2439.3 2440.1 2445.2 2373.6 2395.0 2405.5 2406.7 2411.2 2367.2 2401.5 2405.6 2409.0 2412.9 8601.7 8609.5 8664.7 8674.1 8695.3 5209.9 5351.7 5366.7 5395.0 5412.4 3453.6 3492.0 3561.6 3566.2 3581.0 1016.7 1032.4 1034.5 1041.0 1046.2 11422.4 11569.4 11571.9 11584.3 11598.8 4434.2 4463.6 4658.3 4677.6 4680.6 9054.3 9060.5 9075.9 9091.3 9096.7 5237.7 5238.2 5241.9 5268.8 5270.2 4033.3 4387.5 4501.3 4752.0 4900.2 3465.3 3735.0 3820.8 3856.8 3901.1 1041.8 1124.9 1209.7 1211.0 1228.1 901.1 902.3 911.3 935.5 948.8 5699.2 5867.8 5868.8 5978.4 6035.2 430.8 452.2 459.3 463.5 469.6 3847.8 3910.3 3918.7 3969.3 3989.9 10214.6 10613.3 10776.5 10782.4 10791.6 10710.0 10714.5 10720.4 10721.0 10735.5 7799.7 8007.7 8154.7 8184.2 8201.4 5699.2 5836.9 5855.5 5882.9 5897.4 9588.0 9763.2 9814.8 9960.9 10051.3 9727.4 9948.7 10024.7 10098.1 10132.7 6291.6 6667.6 6911.3 6920.4 6921.2 8244.9 8251.6 8334.9 8337.0 8345.5 6112.0 7086.6 7225.7 7332.5 7402.3 7191.8 7197.5 7208.0 7285.3 7318.5 6774.8 6821.1 6846.6 6857.7 6862.0 3994.6 4741.1 4788.0 4856.1 4930.4 4853.6 4874.7 4956.4 4959.2 5002.7 13:41:21 INFO - PROCESS | 1662 | 13:41:21 INFO - PROCESS | 1662 | [#4] prototype.html Cycles:180 Average:5071.63 Median:5752.00 stddev:1588.60 (27.6%) stddev-sans-first:1552.24 13:41:21 INFO - PROCESS | 1662 | Values: 304.5 313.6 322.5 325.3 325.8 5407.6 5724.1 5949.7 6043.2 6063.9 4345.0 5236.0 5643.4 5909.1 6020.2 4043.0 4469.5 4472.9 4479.9 4531.8 4168.3 4445.8 4468.9 4503.7 4512.5 4343.2 4377.9 4384.3 4387.0 4405.5 4387.5 4407.6 4414.5 4416.5 4426.6 3640.9 3757.1 3788.8 3803.8 3817.0 4743.5 5598.1 5669.5 5700.7 5708.4 5022.2 5032.7 5052.1 5052.2 5053.8 4998.9 5326.0 6018.4 6068.4 6204.2 3781.5 4142.5 4159.9 4160.5 4173.4 5519.4 5779.9 6572.3 6656.9 6689.3 3943.0 3992.5 4208.5 4231.0 4260.6 3965.2 3983.8 4249.0 4320.3 4339.9 5050.5 5061.5 5065.5 5080.0 5095.7 6048.7 6321.3 6355.1 6380.7 6410.9 1209.8 1463.5 1487.6 1490.3 1497.1 950.7 961.6 966.5 970.7 978.0 4910.4 5791.8 5870.0 5892.8 5913.4 6445.4 6466.8 6472.8 6479.4 6481.6 5190.0 5214.5 5578.2 5905.4 5974.4 3304.9 3464.2 3482.1 3487.4 3488.9 6566.4 6779.4 6814.1 6820.1 6847.9 5857.2 6182.7 6718.3 6720.3 6758.0 5953.9 5960.3 6200.8 6346.2 6462.4 5684.9 5954.8 6311.6 6515.7 6549.9 6415.5 6426.5 6448.0 6455.9 6465.0 6472.7 6507.9 6790.9 6824.5 6846.2 3953.5 4081.4 4175.1 4261.6 4265.3 5211.6 5917.9 6135.4 6512.6 6609.2 6055.6 6251.8 6322.5 6331.2 6333.3 5603.5 5945.5 6305.6 6310.2 6316.9 5902.3 6193.5 6203.9 6207.3 6231.7 5284.9 6323.7 6333.2 6345.6 6346.2 6209.4 6219.2 6219.7 6226.3 6261.7 13:41:21 INFO - PROCESS | 1662 | 13:41:21 INFO - PROCESS | 1662 | [#5] yui.html Cycles:180 Average:17961.76 Median:66302.18 stddev:25274.01 (38.1%) stddev-sans-first:25311.20 13:41:21 INFO - PROCESS | 1662 | Values: 579.4 727.9 734.4 734.6 785.4 13649.6 14314.0 14805.6 15279.5 15299.6 13882.0 15500.6 15543.2 15573.3 15583.6 879.7 884.9 887.4 888.7 889.3 726.1 862.8 884.8 892.9 895.7 909.0 939.6 941.6 942.3 942.4 925.0 930.3 932.5 932.8 932.9 692.0 693.8 693.8 694.8 695.7 14688.5 14760.7 14790.6 15054.4 15122.0 12292.0 12297.3 12308.6 12528.4 12539.1 9702.4 10048.8 10110.7 10122.7 10344.6 3056.7 3442.7 3461.5 3497.5 3722.2 71405.6 81445.6 81459.0 84728.2 85066.7 9323.9 9328.3 9380.0 9447.9 9805.7 20694.6 20790.6 20831.0 20836.5 20852.7 7169.0 7203.6 7373.0 7469.8 7659.3 3726.2 4021.9 4057.2 4057.7 4059.5 3368.3 3495.8 3516.4 3580.8 3748.8 676.0 679.1 681.5 694.9 699.5 494.3 545.5 556.1 570.0 602.0 7543.2 7592.6 7600.0 7824.9 8153.6 552.6 556.9 563.0 574.7 580.5 3740.2 3745.0 3809.4 3847.3 3935.3 87747.6 88204.0 89663.0 90055.5 94075.0 76492.6 76965.0 77410.0 78660.0 79487.0 67866.0 68691.0 70447.3 70918.0 71939.3 7331.6 7388.0 7452.9 7649.6 7807.4 43361.8 45008.0 45385.0 45411.5 45496.3 65107.0 65759.3 66256.0 66348.4 66574.7 9544.8 9953.0 10826.0 11003.8 11057.8 11649.7 12087.3 12333.9 12577.2 12609.4 11188.8 11213.0 11252.3 11420.6 11665.8 11025.8 11039.0 11324.3 11401.7 11777.2 11547.1 11665.6 11707.7 11768.5 11792.8 7772.5 8235.2 9603.0 9719.7 9721.8 9044.0 9182.1 9258.0 9544.1 9549.0 13:41:21 INFO - PROCESS | 1662 | -------- Summary: end -------- 13:41:21 INFO - PROCESS | 1662 | 13:41:22 INFO - TEST-INFO | 1662: exit 0 13:41:22 INFO - TEST-OK | dromaeo_css | took 1115641ms 13:41:22 INFO - TEST-START | kraken 13:41:22 INFO - operating with platform_type : mac_ 13:41:22 INFO - Initialising browser for kraken test... 13:41:22 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpzIP_mY/profile http://localhost:49275/getInfo.html) 13:41:24 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 13:41:24 INFO - PROCESS | 1669 | colorDepth:24 13:41:24 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 13:41:24 INFO - PROCESS | 1669 | __metrics 13:41:24 INFO - PROCESS | 1669 | 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" 13:41:24 ERROR - PROCESS | 1669 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 13:41:25 INFO - TEST-INFO | 1669: exit 0 13:41:25 INFO - Browser initialized. 13:41:25 INFO - Running cycle 1/1 for kraken test... 13:41:25 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/T/tmpzIP_mY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:41:37 INFO - PROCESS | 1671 | RSS: Main: 238788608 13:41:37 INFO - PROCESS | 1671 | 13:41:40 INFO - PROCESS | 1671 | 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) 13:41:41 INFO - PROCESS | 1671 | RSS: Main: 285872128 13:41:41 INFO - PROCESS | 1671 | 13:41:43 INFO - PROCESS | 1671 | 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) 13:41:44 INFO - PROCESS | 1671 | RSS: Main: 299110400 13:41:44 INFO - PROCESS | 1671 | 13:41:48 INFO - PROCESS | 1671 | 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) 13:41:48 INFO - PROCESS | 1671 | RSS: Main: 387477504 13:41:48 INFO - PROCESS | 1671 | 13:41:50 INFO - PROCESS | 1671 | 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) 13:41:51 INFO - PROCESS | 1671 | RSS: Main: 286572544 13:41:51 INFO - PROCESS | 1671 | 13:41:53 INFO - PROCESS | 1671 | 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) 13:41:53 INFO - PROCESS | 1671 | RSS: Main: 290754560 13:41:53 INFO - PROCESS | 1671 | 13:41:56 INFO - PROCESS | 1671 | 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) 13:41:57 INFO - PROCESS | 1671 | RSS: Main: 328974336 13:41:57 INFO - PROCESS | 1671 | 13:42:00 INFO - PROCESS | 1671 | 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) 13:42:00 INFO - PROCESS | 1671 | RSS: Main: 329404416 13:42:00 INFO - PROCESS | 1671 | 13:42:03 INFO - PROCESS | 1671 | 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) 13:42:04 INFO - PROCESS | 1671 | RSS: Main: 325689344 13:42:04 INFO - PROCESS | 1671 | 13:42:06 INFO - PROCESS | 1671 | 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) 13:42:06 INFO - PROCESS | 1671 | RSS: Main: 321015808 13:42:06 INFO - PROCESS | 1671 | 13:42:08 INFO - PROCESS | 1671 | 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) 13:42:09 INFO - PROCESS | 1671 | RSS: Main: 328794112 13:42:09 INFO - PROCESS | 1671 | 13:42:11 INFO - PROCESS | 1671 | 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) 13:42:12 INFO - PROCESS | 1671 | RSS: Main: 329703424 13:42:12 INFO - PROCESS | 1671 | 13:42:14 INFO - PROCESS | 1671 | 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) 13:42:15 INFO - PROCESS | 1671 | RSS: Main: 334802944 13:42:15 INFO - PROCESS | 1671 | 13:42:18 INFO - PROCESS | 1671 | 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) 13:42:19 INFO - PROCESS | 1671 | RSS: Main: 332148736 13:42:19 INFO - PROCESS | 1671 | 13:42:20 INFO - PROCESS | 1671 | 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) 13:42:21 INFO - PROCESS | 1671 | RSS: Main: 396955648 13:42:21 INFO - PROCESS | 1671 | 13:42:21 INFO - PROCESS | 1671 | __start_tp_report 13:42:21 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 13:42:21 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 13:42:21 INFO - PROCESS | 1671 | |i|pagename|runs| 13:42:21 INFO - PROCESS | 1671 | |0;ai-astar;113;111;111;112;102;157;174;165;126;112 13:42:21 INFO - PROCESS | 1671 | |1;audio-beat-detection;155;123;159;200;149;145;146;114;158;139 13:42:21 INFO - PROCESS | 1671 | |2;audio-dft;186;185;188;188;184;186;184;184;186;183 13:42:21 INFO - PROCESS | 1671 | |3;audio-fft;82;72;86;93;56;56;58;81;85;56 13:42:21 INFO - PROCESS | 1671 | |4;audio-oscillator;94;93;135;77;80;81;124;80;77;126 13:42:21 INFO - PROCESS | 1671 | |5;imaging-gaussian-blur;89;86;90;88;89;87;90;87;90;92 13:42:21 INFO - PROCESS | 1671 | |6;imaging-darkroom;93;95;95;94;94;95;95;109;149;149 13:42:21 INFO - PROCESS | 1671 | |7;imaging-desaturate;85;83;87;85;84;84;89;86;86;87 13:42:21 INFO - PROCESS | 1671 | |8;json-parse-financial;61;61;60;63;67;86;89;98;60;58 13:42:21 INFO - PROCESS | 1671 | |9;json-stringify-tinderbox;62;63;73;76;72;66;68;70;68;68 13:42:21 INFO - PROCESS | 1671 | |10;stanford-crypto-aes;80;82;82;82;81;106;104;83;82;96 13:42:21 INFO - PROCESS | 1671 | |11;stanford-crypto-ccm;136;138;141;135;138;155;146;141;187;155 13:42:21 INFO - PROCESS | 1671 | |12;stanford-crypto-pbkdf2;171;171;215;170;174;173;168;166;177;163 13:42:21 INFO - PROCESS | 1671 | |13;stanford-crypto-sha256-iterative;68;67;68;69;69;67;68;68;68;70 13:42:21 INFO - PROCESS | 1671 | __end_tp_report 13:42:21 INFO - PROCESS | 1671 | __start_cc_report 13:42:21 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,2021 13:42:21 INFO - PROCESS | 1671 | __end_cc_report 13:42:21 INFO - PROCESS | 1671 | __startTimestamp1460752941084__endTimestamp 13:42:21 INFO - PROCESS | 1671 | 13:42:21 INFO - PROCESS | 1671 | ------- Summary: start ------- 13:42:21 INFO - PROCESS | 1671 | Number of tests: 14 13:42:21 INFO - PROCESS | 1671 | 13:42:21 INFO - PROCESS | 1671 | [#0] ai-astar Cycles:10 Average:128.30 Median:119.50 stddev:26.50 (22.2%) stddev-sans-first:27.52 13:42:21 INFO - PROCESS | 1671 | Values: 113.0 111.0 111.0 112.0 102.0 157.0 174.0 165.0 126.0 112.0 13:42:21 INFO - PROCESS | 1671 | 13:42:21 INFO - PROCESS | 1671 | [#1] audio-beat-detection Cycles:10 Average:148.80 Median:152.00 stddev:23.20 (15.3%) stddev-sans-first:24.50 13:42:21 INFO - PROCESS | 1671 | Values: 155.0 123.0 159.0 200.0 149.0 145.0 146.0 114.0 158.0 139.0 13:42:21 INFO - PROCESS | 1671 | 13:42:21 INFO - PROCESS | 1671 | [#2] audio-dft Cycles:10 Average:185.40 Median:186.00 stddev:1.71 (0.9%) stddev-sans-first:1.80 13:42:21 INFO - PROCESS | 1671 | Values: 186.0 185.0 188.0 188.0 184.0 186.0 184.0 184.0 186.0 183.0 13:42:21 INFO - PROCESS | 1671 | 13:42:21 INFO - PROCESS | 1671 | [#3] audio-fft Cycles:10 Average:72.50 Median:81.50 stddev:14.71 (18.1%) stddev-sans-first:15.20 13:42:21 INFO - PROCESS | 1671 | Values: 82.0 72.0 86.0 93.0 56.0 56.0 58.0 81.0 85.0 56.0 13:42:21 INFO - PROCESS | 1671 | 13:42:21 INFO - PROCESS | 1671 | [#4] audio-oscillator Cycles:10 Average:96.70 Median:80.00 stddev:22.78 (28.5%) stddev-sans-first:24.15 13:42:21 INFO - PROCESS | 1671 | Values: 94.0 93.0 135.0 77.0 80.0 81.0 124.0 80.0 77.0 126.0 13:42:21 INFO - PROCESS | 1671 | 13:42:21 INFO - PROCESS | 1671 | [#5] imaging-gaussian-blur Cycles:10 Average:88.80 Median:89.50 stddev:1.81 (2.0%) stddev-sans-first:1.92 13:42:21 INFO - PROCESS | 1671 | Values: 89.0 86.0 90.0 88.0 89.0 87.0 90.0 87.0 90.0 92.0 13:42:21 INFO - PROCESS | 1671 | 13:42:21 INFO - PROCESS | 1671 | [#6] imaging-darkroom Cycles:10 Average:106.80 Median:94.50 stddev:22.71 (24.0%) stddev-sans-first:23.53 13:42:21 INFO - PROCESS | 1671 | Values: 93.0 95.0 95.0 94.0 94.0 95.0 95.0 109.0 149.0 149.0 13:42:21 INFO - PROCESS | 1671 | 13:42:21 INFO - PROCESS | 1671 | [#7] imaging-desaturate Cycles:10 Average:85.60 Median:86.00 stddev:1.78 (2.1%) stddev-sans-first:1.87 13:42:21 INFO - PROCESS | 1671 | Values: 85.0 83.0 87.0 85.0 84.0 84.0 89.0 86.0 86.0 87.0 13:42:21 INFO - PROCESS | 1671 | 13:42:21 INFO - PROCESS | 1671 | [#8] json-parse-financial Cycles:10 Average:70.30 Median:65.00 stddev:14.77 (22.7%) stddev-sans-first:15.28 13:42:21 INFO - PROCESS | 1671 | Values: 61.0 61.0 60.0 63.0 67.0 86.0 89.0 98.0 60.0 58.0 13:42:21 INFO - PROCESS | 1671 | 13:42:21 INFO - PROCESS | 1671 | [#9] json-stringify-tinderbox Cycles:10 Average:68.60 Median:69.00 stddev:4.35 (6.3%) stddev-sans-first:3.91 13:42:21 INFO - PROCESS | 1671 | Values: 62.0 63.0 73.0 76.0 72.0 66.0 68.0 70.0 68.0 68.0 13:42:21 INFO - PROCESS | 1671 | 13:42:21 INFO - PROCESS | 1671 | [#10] stanford-crypto-aes Cycles:10 Average:87.80 Median:82.00 stddev:10.14 (12.4%) stddev-sans-first:10.36 13:42:21 INFO - PROCESS | 1671 | Values: 80.0 82.0 82.0 82.0 81.0 106.0 104.0 83.0 82.0 96.0 13:42:21 INFO - PROCESS | 1671 | 13:42:21 INFO - PROCESS | 1671 | [#11] stanford-crypto-ccm Cycles:10 Average:147.20 Median:143.50 stddev:15.73 (11.0%) stddev-sans-first:16.16 13:42:21 INFO - PROCESS | 1671 | Values: 136.0 138.0 141.0 135.0 138.0 155.0 146.0 141.0 187.0 155.0 13:42:21 INFO - PROCESS | 1671 | 13:42:21 INFO - PROCESS | 1671 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.80 Median:172.00 stddev:14.68 (8.5%) stddev-sans-first:15.51 13:42:21 INFO - PROCESS | 1671 | Values: 171.0 171.0 215.0 170.0 174.0 173.0 168.0 166.0 177.0 163.0 13:42:21 INFO - PROCESS | 1671 | 13:42:21 INFO - PROCESS | 1671 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.20 Median:68.00 stddev:0.92 (1.4%) stddev-sans-first:0.97 13:42:21 INFO - PROCESS | 1671 | Values: 68.0 67.0 68.0 69.0 69.0 67.0 68.0 68.0 68.0 70.0 13:42:21 INFO - PROCESS | 1671 | -------- Summary: end -------- 13:42:21 INFO - PROCESS | 1671 | 13:42:21 INFO - TEST-INFO | 1671: exit 0 13:42:21 INFO - TEST-OK | kraken | took 59465ms 13:42:21 INFO - SUITE-END | took 1175s 13:42:22 INFO - Completed test suite (00:19:35) 13:42:22 INFO - javascript benchmark 13:42:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3394.100563350712, 3472.1666799967998, 3488.2849015951724, 3502.2120022994845, 3537.964620353796, 5089.567386772124, 5394.676319420832, 5599.384067752544, 5700.458456446641, 5735.397783232763, 5697.6866272355, 5706.65760054397, 5757.913631295523, 5847.4152584741505, 5856.912146317798, 5137.614678899079, 5173.405058418273, 5221.712805795699, 5235.397929238148, 5322.414534401213, 5282.339707536558, 5291.841244762644, 5340.62615616905, 5347.331583552056, 5387.568994480433, 5135.948640513603, 5152.536271735562, 5216.634835561512, 5269.367675878881, 5284.180951952453, 5205.089109405877, 5220.843374698765, 5222.3733152022005, 5230.947690523122, 5233.502817232356, 5190.792368305263, 5198.818041368571, 5217.3217481727515, 5255.395629502618, 5261.0, 5515.227868098469, 5590.972045139789, 5626.184203290541, 5644.379118296984, 5690.573207009491, 6231.40801623845, 6246.219222597176, 6260.874782504325, 6272.0, 6277.152584401093, 4845.83039593616, 6298.2756982947085, 6300.180976473029, 6309.526785491106, 6314.526410519184, 5679.460451257124, 5774.884502309972, 5793.8841223175305, 5805.854853628651, 5897.67562784051, 6276.6547839869245, 6305.842353941096, 6309.621422714652, 6313.747450101944, 6334.176557047646, 6151.72317245716, 6197.783077592353, 6218.9427797273665, 6218.968905155445, 6222.315545290015, 6102.246889800143, 6207.751689932443, 6316.652584107826, 6332.746690132343, 6334.334894835976, 6195.287541932736, 6236.594621349598, 6237.189165408558, 6240.781572644935, 6245.219347581552, 6246.4378205960775, 6254.437100660963, 6254.468370188494, 6264.185655864709, 6280.403361680633, 4744.335792988984, 4771.475137734847, 4837.443693975168, 4838.806447742121, 4840.99999999993, 4443.0, 4457.866264012085, 4488.3940668010055, 4489.169503641837, 4497.9325310120375, 5628.015097358028, 5636.492715655529, 5649.067903795828, 5659.207710920475, 5663.66018038919, 6181.629102253879, 6184.381561843779, 6250.874982500324, 6261.092141639528, 6290.182276304051, 5641.746121424546, 5665.405132461115, 5673.744681489343, 5677.602567820296, 5697.173909783059, 5392.622516423891, 5400.756965936543, 5409.891802163935, 5410.594205434608, 5416.783328666809, 6189.226346706661, 6332.873342533216, 6356.4279214870885, 6381.074744162071, 6423.839404014937, 6257.4994000480765, 6275.090111933835, 6302.968485157545, 6313.494920406449, 6317.5893566918, 6345.619262844244, 6375.808725738235, 6400.55196136266, 6401.231852177769, 6409.391107844747, 6080.817575472743, 6109.526129009804, 6147.84630384244, 6219.315875253809, 6227.377262273736, 6256.186695729526, 6279.466245369194, 6280.9685951569945, 6292.2134733158355, 6298.71655775482, 5954.791582294599, 6018.067199584071, 6112.602680825732, 6137.110119032626, 6144.0, 6042.395760423922, 6110.0424004731585, 6113.233070740914, 6121.666376154749, 6135.07973803933, 5642.407547207484, 5883.294004719461, 5923.348431672426, 5926.377730338252, 5951.970240148945, 4707.646926480459, 5855.385184555731, 6130.6010765251895, 6131.07927964819, 6139.6009259398, 5578.940001399721, 5864.736086876186, 5877.500412464989, 5886.705664716661, 5886.911696324473], "value": 5695.516585787246, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [509.2615707224523, 522.4957915611435, 575.4015823543514, 599.0804115682429, 620.2433031701323, 25049.373765655866, 30561.69438305616, 37889.43165852514, 38322.808385958066, 39354.606453935456, 41071.0, 41787.58212417875, 41893.0, 41900.37149442761, 41936.79031604838, 9402.0, 9614.903850961471, 9619.56711947964, 9626.759331016745, 9639.939606643264, 8635.43869648474, 10430.061294483496, 10579.153667706565, 11635.127365447583, 11965.641030769091, 11504.654860354161, 11610.36143012134, 11642.534298628045, 11648.708782280468, 11662.47518861649, 11583.594574189905, 11736.061115110857, 11747.823782643181, 11752.294862308288, 11765.470488836481, 11494.13793965456, 11601.303921764722, 11621.186516943817, 11625.069994400428, 11827.586034488751, 39856.80429587117, 40284.0, 40351.0, 40361.39457908134, 40507.7974610128, 3305.5206994985833, 3388.7119594834467, 3544.0, 3622.963770362289, 3785.962140378616, 28822.423551528853, 28960.042233810025, 29036.0, 29296.8535157325, 29497.70502294971, 2687.4087700705813, 2704.445588654341, 2712.864356782153, 2837.205582436921, 2920.5765164051095, 439775.0, 441231.0, 442352.0, 442452.0, 442937.7853110746, 62487.6875615619, 62843.0, 62900.37099628945, 63142.36857631457, 63268.68365658142, 54790.72604636951, 65679.0, 65856.68286634337, 66103.66948165324, 66111.33888661148, 15077.0, 16784.748228776578, 17018.319267229173, 17030.914845425694, 17041.147942603067, 11699.532018719156, 11788.46951887167, 11791.410429478492, 11791.587294444653, 12798.23210607348, 13915.58253252404, 17410.129493525525, 17435.302587896596, 17864.285428582712, 17870.731939020927, 6146.877062458726, 7510.286522780327, 7535.60897596073, 7565.962170189114, 7617.847643047219, 978.2858513285341, 989.2877128467492, 1083.1443159903588, 1083.9620613278498, 1099.8240281554913, 29195.854020730185, 29251.85374073116, 29787.70212297849, 29906.40187196244, 30116.69883301183, 1084.2952081147316, 1097.983530247047, 1108.6729414822646, 1155.7688462307572, 1157.8375739217668, 14925.17911514877, 15122.39510419593, 15307.693846123238, 15470.994385364915, 15583.06501609907, 541447.0, 546885.0, 549857.0, 549939.0, 551530.0, 58485.83028339409, 58871.70564147152, 58908.11637825436, 58964.70517647384, 59037.0, 1426.6290764401124, 1443.6246575890134, 1457.4316016753687, 1470.9190994495405, 1482.918439485817, 28361.72372243213, 28780.11468451732, 28996.42007159803, 29056.854715726287, 29610.0, 49059.0, 49341.75329123331, 49854.0, 49870.50129498804, 49908.75228119326, 369419.0, 370112.2988770078, 372015.0, 373486.0, 373933.0, 35253.29493410169, 39010.0, 39268.60731392649, 40758.592414076666, 41560.79219603883, 41696.79151604223, 42564.36153457639, 43088.569114308455, 43359.56640433555, 43872.78063609661, 35936.82031589825, 37678.24643507169, 38331.616683832806, 39378.0, 39903.40144897887, 35216.94349169529, 35338.64661353456, 35671.92984210478, 36118.81940590385, 36211.27577448489, 35154.0, 35224.0, 35226.76706315266, 36512.26975460529, 36577.63422365849, 30581.847090764404, 31606.683933160373, 31720.0, 31726.730930763435, 31884.0, 23625.0, 24198.63702044506, 24255.0, 24844.50310993806, 25355.6196657054], "value": 19874.97402206133, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.960703929607, 393.1566789237086, 394.50686641697877, 396.44299758838844, 405.1774896959172, 14139.872601804795, 15695.921520392381, 19079.713804292947, 19893.3037343693, 19929.0, 16238.594035149126, 18483.0, 19904.0, 20163.19347226113, 20519.17923283067, 5659.462351076651, 5823.06830907055, 5863.2670916135485, 5872.911906321408, 5874.5006674432725, 5663.546916246691, 5854.941450585482, 5874.236349274588, 5897.380775018625, 6063.9090413643835, 3918.921621567581, 4153.0, 5794.275715535558, 6042.879142417149, 6131.172291740625, 6034.0, 6094.573379863411, 6112.358202388752, 6123.357047510036, 6124.448799608012, 4676.532346765331, 5745.368009518991, 5779.88440231193, 5781.82654520365, 5790.971045144789, 10545.630902918361, 12764.87235127646, 12953.352332383438, 12985.870141298561, 13284.202947823065, 1948.2499237793552, 2164.9567008659897, 2213.269621025058, 2259.2610910529766, 2751.0783887397747, 7522.699092036312, 9522.190613797835, 9996.70009899697, 10857.837132443019, 14763.818894488415, 1592.8884978051542, 1638.6313079557124, 1684.6378028723884, 1686.5405496946664, 1711.1273250642137, 113964.43017784941, 120712.99999999913, 120804.0, 121341.0, 121576.39211803973, 11391.544338226544, 11537.0, 11573.826392604118, 11615.593454229062, 11730.354830484406, 14623.488177913721, 14775.18736469483, 14786.334614942138, 14791.92604036973, 14812.777808332668, 8418.747437576883, 8510.234078932803, 8515.21180552091, 8516.744497664957, 8615.612297446505, 6676.399124078759, 6736.696848641823, 7019.4384449243935, 8450.281726053354, 8468.0, 3708.554973403155, 3843.0, 4472.798724057425, 4537.500874903758, 4596.931046034312, 1748.094576911173, 1880.7366968624403, 1929.199382256348, 1933.9983117473348, 1942.3298961858136, 871.808202195516, 913.9268858491438, 925.3892430995511, 979.6179489998906, 988.6391467114452, 19402.96279340537, 20034.298799542237, 20197.899010504854, 20293.18827246923, 20338.288159914333, 928.7120992492349, 931.6320053578798, 956.383132879289, 986.0632399220625, 992.7964767222702, 10354.896451035393, 10633.46832658364, 10647.787044259227, 10649.520771565298, 10685.252032357816, 578759.0, 589532.0, 596367.0, 599438.0, 600737.0, 119166.0, 122493.38753306176, 131271.3436432831, 132676.33661831822, 133069.0, 450.6845208354165, 461.42552522110185, 467.4320700349092, 468.73516463198183, 469.4648101164607, 8629.654813807378, 10508.896565860474, 10671.306365086244, 10680.092192163442, 10873.45632718345, 33405.99782006544, 48977.755111224214, 49694.50305496899, 50034.49965500444, 50515.0, 74699.0, 75911.62044189744, 77017.22982770325, 77070.22929770855, 77944.0, 7531.924680753123, 7824.295813376823, 7934.404919631166, 8090.271875531231, 8172.509649421054, 11889.583864564698, 11939.582114626293, 12088.637340879788, 12135.453904574491, 12398.876011239772, 10294.970502949645, 10558.788824223626, 10663.200259980378, 10736.62421815264, 10800.641070308928, 10153.441560714231, 10338.586456541654, 10447.68656940293, 10554.261201716114, 10650.0, 10463.372197668165, 10767.676969690921, 11050.834237486604, 11141.944290278496, 11169.441527923735, 7832.882506762289, 7908.723194688158, 8046.396520260887, 8101.675932962616, 8292.958535207285, 9183.954080229556, 10593.205509586658, 10614.575416983234, 10727.731806704895, 10912.836307455553], "value": 8797.510752636488, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1423.1176670464301, 1572.606848287928, 1603.5991002249439, 1842.9447116586498, 1977.9901100494496, 7750.883736743946, 8032.558209298486, 8035.3571714262935, 8570.057293697688, 8790.208881200691, 8455.365847561427, 8543.316534677226, 8629.65481380744, 8639.222469977702, 8669.69656062038, 2403.0628055058496, 2437.5612389769835, 2438.9512209755794, 2439.0, 2439.780419762221, 2390.1634427950253, 2416.867072311022, 2433.4038160650666, 2439.6706444630013, 2441.0479912834007, 2425.8908349124245, 2431.5623187826186, 2439.3047981325326, 2440.1093600835743, 2445.1686426614947, 2373.6439534069855, 2394.9640755388596, 2405.506871091422, 2406.6750988616486, 2411.2043025801618, 2367.1596583213054, 2401.5076909233567, 2405.579023670868, 2409.0123049549775, 2412.9396765080837, 8601.655933762642, 8609.483430994098, 8664.653413863438, 8674.132586741276, 8695.304375649934, 5209.921851172236, 5351.652142610717, 5366.70483123428, 5395.0, 5412.404635490093, 3453.620101788801, 3492.0, 3561.554805649294, 3566.197605538759, 3580.964190358115, 1016.7458135466134, 1032.359936839157, 1034.4775888176498, 1041.0, 1046.1944302886811, 11422.428878556038, 11569.42152892352, 11571.94214028933, 11584.30494170344, 11598.826017609743, 4434.17967675981, 4463.598276155161, 4658.254679251371, 4677.64917631179, 4680.625549955996, 9054.320925930582, 9060.54697265134, 9075.863862042075, 9091.31815113869, 9096.681616143404, 5237.685738855681, 5238.240455133984, 5241.9213711794355, 5268.789248430096, 5270.235815806686, 4033.2740106780475, 4387.5393083726385, 4501.3022981437925, 4752.0, 4900.191468407744, 3465.2722928184858, 3734.9626503735158, 3820.8471661133244, 3856.768593884376, 3901.0637447012514, 1041.770810421706, 1124.9043831274269, 1209.661294837447, 1210.9939450302868, 1228.121892846619, 901.1034021148868, 902.273669695901, 911.2709832134399, 935.5135329628555, 948.8054948735493, 5699.204750503661, 5867.794627188028, 5868.823935281948, 5978.432048955342, 6035.231298966944, 430.79537219820963, 452.2492662180764, 459.3431393107821, 463.46932761987335, 469.6078774223493, 3847.788371639587, 3910.2570511602703, 3918.686505079587, 3969.3450580653866, 3989.92020159601, 10214.591416343412, 10613.257072004886, 10776.461176941122, 10782.353058816496, 10791.56833726642, 10710.0, 10714.46427678613, 10720.410377429316, 10721.0, 10735.463226838627, 7799.688012479551, 8007.679692812222, 8154.714584989379, 8184.2224988625985, 8201.42590018693, 5699.230603868425, 5836.883262334644, 5855.531557475477, 5882.941170588239, 5897.3807750185615, 9587.952060239653, 9763.218942484442, 9814.803703926023, 9960.900390995997, 10051.296409251283, 9727.416355018722, 9948.651797187063, 10024.749381265527, 10098.141657959155, 10132.696019119438, 6291.559590828598, 6667.599944003376, 6911.343422374866, 6920.377166055079, 6921.169459664872, 8244.876326854983, 8251.587420629065, 8334.916650833415, 8337.041240257206, 8345.499270043816, 6111.96944015277, 7086.645667716697, 7225.674844631899, 7332.486725929347, 7402.259774022555, 7191.7842464726145, 7197.532160409557, 7208.0, 7285.344319011315, 7318.487705860539, 6774.762883299061, 6821.147356580427, 6846.623435711083, 6857.691403886937, 6861.965690171517, 3994.5805690403254, 4741.075490279362, 4788.0, 4856.1016212000895, 4930.433000205022, 4853.611711063177, 4874.731889746098, 4956.430010548689, 4959.156943319572, 5002.674826136289], "value": 4432.104708845725, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.50973931969526, 313.62835040477034, 322.4856354115186, 325.3168346472407, 325.784981911938, 5407.648087978006, 5724.055530837407, 5949.720810025844, 6043.18417013703, 6063.939360606392, 4344.978275108628, 5236.031334203175, 5643.379228284885, 5909.079549146534, 6020.247469066367, 4042.959570404302, 4469.53069927657, 4472.865814025568, 4479.910401791963, 4531.750753708545, 4168.312228482297, 4445.777711114448, 4468.865934021985, 4503.662225333097, 4512.458504979407, 4343.1747967886, 4377.912441751163, 4384.254676704968, 4386.97806510967, 4405.515393306734, 4387.517373088974, 4407.625351845097, 4414.536473670282, 4416.536263692298, 4426.601605855489, 3640.890773276806, 3757.079515518688, 3788.8484460621544, 3803.7717736935874, 3817.0457385653585, 4743.478217396084, 5598.1322894951345, 5669.489745922846, 5700.714964251771, 5708.372079071299, 5022.221555658879, 5032.672876263068, 5052.1411360068505, 5052.166392545226, 5053.84838454847, 4998.850034498971, 5326.0, 6018.428249316352, 6068.362821903681, 6204.224471941007, 3781.5084039074745, 4142.544320124784, 4159.880089932551, 4160.45914031174, 4173.373993900909, 5519.420460851593, 5779.942200578024, 6572.277049524549, 6656.93343066568, 6689.33106689332, 3942.9605703943166, 3992.4647848616337, 4208.453885399351, 4231.0, 4260.573942605776, 3965.167314863911, 3983.820728067244, 4249.001484651105, 4320.308750599889, 4339.934900976488, 5050.4697006814495, 5061.519155680205, 5065.493450654905, 5080.044182989575, 5095.745212739423, 6048.727807248596, 6321.273053598803, 6355.110284560073, 6380.680965951684, 6410.903836442457, 1209.7520008398258, 1463.5389852196738, 1487.5983484459136, 1490.3070072416378, 1497.101738956639, 950.7290422229631, 961.6201600367815, 966.5457235099492, 970.7039352997263, 977.9657711980188, 4910.386201724785, 5791.797287095016, 5869.970650146722, 5892.793752218652, 5913.438223368773, 6445.355464453517, 6466.805995820133, 6472.838179045468, 6479.384458476437, 6481.606454612281, 5190.013897359565, 5214.45248248936, 5578.24693666358, 5905.379935106838, 5974.3726908674835, 3304.884329048472, 3464.2378676691087, 3482.1294676330917, 3487.3722729908354, 3488.930221395558, 6566.4418524425, 6779.406839392739, 6814.1482314710665, 6820.147481564804, 6847.897281540781, 5857.15071314657, 6182.69086545671, 6718.260991290954, 6720.260771315151, 6758.0, 5953.910691339634, 5960.314563825215, 6200.80658460893, 6346.17499725042, 6462.4183823456115, 5684.914726279192, 5954.791582294599, 6311.589746666452, 6515.729432760756, 6549.934500655123, 6415.518836087405, 6426.518011149089, 6448.000559913221, 6455.870882582416, 6465.030245463218, 6472.708728107153, 6507.869842603216, 6790.8641827164165, 6824.488163387865, 6846.212685541226, 3953.486046814011, 4081.40819581165, 4175.060611362419, 4261.595148460891, 4265.274903266514, 5211.60912931524, 5917.881642367042, 6135.447809697149, 6512.6092434454085, 6609.20689517261, 6055.637481566612, 6251.843703907439, 6322.49420046386, 6331.176946996861, 6333.334999825136, 5603.495685388335, 5945.464908158287, 6305.621662700252, 6310.1796766420075, 6316.873662526816, 5902.321233058082, 6193.473554747897, 6203.937960620336, 6207.348228435949, 6231.688415579293, 5284.920726189033, 6323.747050117944, 6333.1766870306565, 6345.555811093179, 6346.2067241594805, 6209.441150296314, 6219.160413344141, 6219.689015549295, 6226.315105338319, 6261.68691565429], "value": 4553.7794477680545, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [579.3920338538964, 727.9344858962695, 734.3794493652865, 734.5959722152814, 785.3677789379551, 13649.590512284623, 14314.0, 14805.555833325005, 15279.465218717332, 15299.617509562253, 13882.0, 15500.612484687861, 15543.22283885804, 15573.29920153593, 15583.610409739735, 879.6789171952239, 884.8849649545582, 887.427609192074, 888.693400776731, 889.297455010541, 726.1213931143322, 862.7584276402617, 884.7832281091141, 892.8616064509979, 895.6999405199266, 908.9636414543444, 939.5724945149974, 941.5857022909931, 942.3074040580168, 942.4062840410531, 925.0194793518891, 930.3069213435999, 932.4545141092469, 932.7574830544107, 932.9440233586007, 691.989620155698, 693.774523279931, 693.8404167041558, 694.8262934266434, 695.6591270277578, 14688.48590299345, 14760.704785904329, 14790.556283311518, 15054.397824087024, 15122.0, 12292.0, 12297.262164270172, 12308.630741077783, 12528.436220370106, 12539.122261441702, 9702.417854928726, 10048.799024019552, 10110.69667909964, 10122.746931326703, 10344.586216551328, 3056.66376698563, 3442.707369873564, 3461.463473161639, 3497.4578940264046, 3722.181120153562, 71405.57188856194, 81445.59277203635, 81459.0, 84728.15271847325, 85066.72399913945, 9323.90676093244, 9328.300377471716, 9380.0, 9447.90552094484, 9805.656802011896, 20694.586108277752, 20790.58418831615, 20831.0, 20836.47908802292, 20852.687209691867, 7168.964155179191, 7203.567785932861, 7372.96313518429, 7469.813254668677, 7659.310662040443, 3726.16161363691, 4021.8592349267633, 4057.188562287495, 4057.695672824491, 4059.4925634295714, 3368.3263347330144, 3495.8426870790877, 3516.40221162403, 3580.838862251205, 3748.8125593720206, 676.0, 679.0832376291967, 681.5229339462396, 694.8540806430706, 699.4823830365493, 494.31537320810935, 545.5308434746115, 556.0630337880684, 570.0167211560108, 602.0367412140628, 7543.207963163899, 7592.620368981528, 7600.0, 7824.882626760603, 8153.55155466455, 552.5993654600463, 556.8914061757919, 562.9549636029108, 574.681052016135, 580.4746704232676, 3740.214554943438, 3744.981275093607, 3809.3524100902914, 3847.345951188249, 3935.252302062599, 87747.56126219328, 88204.0, 89663.0, 90055.54972225097, 94075.0, 76492.61753691196, 76965.0, 77410.0, 78660.0, 79487.0, 67866.0, 68691.0, 70447.2955270451, 70918.0, 71939.2806071943, 7331.560106393634, 7388.0, 7452.925470745223, 7649.579273140031, 7807.375409967302, 43361.78319108384, 45008.0, 45385.0, 45411.545884540734, 45496.31755523736, 65107.02339464817, 65759.34240657724, 66256.0, 66348.36038936744, 66574.66712666406, 9544.761380965254, 9953.0, 10825.971532704381, 11003.779924401628, 11057.83413248818, 11649.650510484698, 12087.335196564274, 12333.93833030829, 12577.182483138567, 12609.43257553394, 11188.832167517656, 11213.0, 11252.26860254081, 11420.600278990196, 11665.766684666089, 11025.779484410426, 11039.0, 11324.32054076758, 11401.657950261506, 11777.234479758788, 11547.133964952494, 11665.59170429031, 11707.707307317385, 11768.470418831002, 11792.76414471723, 7772.4947878389985, 8235.176482351717, 9602.95198524003, 9719.65981190655, 9721.805563888824, 9044.0, 9182.091503915472, 9257.953710231406, 9544.141027307576, 9549.0], "value": 6786.1313280446675, "unit": "score"}], "name": "dromaeo_css", "value": 7174.763086400322, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 111.0, 111.0, 112.0, 102.0, 157.0, 174.0, 165.0, 126.0, 112.0], "value": 128.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 123.0, 159.0, 200.0, 149.0, 145.0, 146.0, 114.0, 158.0, 139.0], "value": 148.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 185.0, 188.0, 188.0, 184.0, 186.0, 184.0, 184.0, 186.0, 183.0], "value": 185.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 72.0, 86.0, 93.0, 56.0, 56.0, 58.0, 81.0, 85.0, 56.0], "value": 72.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 135.0, 77.0, 80.0, 81.0, 124.0, 80.0, 77.0, 126.0], "value": 96.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 90.0, 88.0, 89.0, 87.0, 90.0, 87.0, 90.0, 92.0], "value": 88.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 95.0, 95.0, 94.0, 94.0, 95.0, 95.0, 109.0, 149.0, 149.0], "value": 106.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 87.0, 85.0, 84.0, 84.0, 89.0, 86.0, 86.0, 87.0], "value": 85.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 61.0, 60.0, 63.0, 67.0, 86.0, 89.0, 98.0, 60.0, 58.0], "value": 70.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 73.0, 76.0, 72.0, 66.0, 68.0, 70.0, 68.0, 68.0], "value": 68.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 82.0, 82.0, 82.0, 81.0, 106.0, 104.0, 83.0, 82.0, 96.0], "value": 87.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 138.0, 141.0, 135.0, 138.0, 155.0, 146.0, 141.0, 187.0, 155.0], "value": 147.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 171.0, 215.0, 170.0, 174.0, 173.0, 168.0, 166.0, 177.0, 163.0], "value": 174.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 68.0, 69.0, 69.0, 67.0, 68.0, 68.0, 68.0, 70.0], "value": 68.2, "unit": "score"}], "name": "kraken", "value": 1529.8, "alertThreshold": 2.0}]} 13:42:22 INFO - Return code: 0 13:42:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 13:42:22 ERROR - # TBPL SUCCESS # 13:42:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:42:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:42:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:42:22 INFO - 2016-04-15 13:42:22.412 screenresolution[1675:9718] starting screenresolution argv=screenresolution get 13:42:22 INFO - 2016-04-15 13:42:22.415 screenresolution[1675:9718] Display 0: 1600x1200x32@60 13:42:22 INFO - 2016-04-15 13:42:22.421 screenresolution[1676:9721] starting screenresolution argv=screenresolution list 13:42:22 INFO - Available Modes on Display 0 13:42:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:42:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:42:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:42:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:42:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:42:22 INFO - Intel Iris: 13:42:22 INFO - Chipset Model: Intel Iris 13:42:22 INFO - Type: GPU 13:42:22 INFO - Bus: Built-In 13:42:22 INFO - VRAM (Dynamic, Max): 1536 MB 13:42:22 INFO - Vendor: Intel (0x8086) 13:42:22 INFO - Device ID: 0x0a2e 13:42:22 INFO - Revision ID: 0x0009 13:42:22 INFO - Displays: 13:42:22 INFO - Display: 13:42:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:42:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:42:22 INFO - Display Serial Number: 1600x1200 60 13:42:22 INFO - Main Display: Yes 13:42:22 INFO - Mirror: Off 13:42:22 INFO - Online: Yes 13:42:22 INFO - Rotation: Supported 13:42:22 INFO - Return code: 0 13:42:22 INFO - Running post-action listener: _resource_record_post_action 13:42:22 INFO - Running post-run listener: _resource_record_post_run 13:42:23 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 32509440; Write bytes: 692624384; Read time: 442; Write time: 2410 13:42:23 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 373334528; Write bytes: 367130112; Read time: 27986; Write time: 689 13:42:23 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 31575552; Write bytes: 296166912; Read time: 379; Write time: 1666 13:42:23 INFO - Running post-run listener: _upload_blobber_files 13:42:23 INFO - Blob upload gear active. 13:42:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:42:23 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 13:42:23 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'] 13:42:23 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 13:42:24 INFO - (blobuploader) - INFO - Open directory for files ... 13:42:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:42:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:42:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:42:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:42:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:42:24 INFO - (blobuploader) - INFO - Done attempting. 13:42:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:42:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:42:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:42:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:42:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:42:25 INFO - (blobuploader) - INFO - Done attempting. 13:42:25 INFO - (blobuploader) - INFO - Iteration through files over. 13:42:25 INFO - Return code: 0 13:42:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:42:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:42:25 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0d88051692dc3cb2fb559c600a0cab9cf756ea4e813447c5108280fe1f06dc1bf50bd1509cd1cab4812a769031757ed8cc71caf3d8ed8126ae88610a539482ca", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0b2179e8f92c4b4bdfeb5e373cc2f84e352a76e4098371370c98d12521f09f6a2f49ed677e4138ea801c97c25462d11330307a801f86974e75a2c585bd0c34cb"} 13:42:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:42:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:42:25 INFO - Contents: 13:42:25 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0d88051692dc3cb2fb559c600a0cab9cf756ea4e813447c5108280fe1f06dc1bf50bd1509cd1cab4812a769031757ed8cc71caf3d8ed8126ae88610a539482ca", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0b2179e8f92c4b4bdfeb5e373cc2f84e352a76e4098371370c98d12521f09f6a2f49ed677e4138ea801c97c25462d11330307a801f86974e75a2c585bd0c34cb"} 13:42:25 INFO - Running post-run listener: copy_logs_to_upload_dir 13:42:25 INFO - Copying logs to upload dir... 13:42:25 INFO - mkdir: /builds/slave/test/build/upload/logs 13:42:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.953900 ========= master_lag: 6.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-04-15 13:42:32.000138) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 8 secs) (at 2016-04-15 13:42:32.004251) ========= 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.iod25OT5aO/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.o0E7P3bGLA/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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/0d88051692dc3cb2fb559c600a0cab9cf756ea4e813447c5108280fe1f06dc1bf50bd1509cd1cab4812a769031757ed8cc71caf3d8ed8126ae88610a539482ca", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0b2179e8f92c4b4bdfeb5e373cc2f84e352a76e4098371370c98d12521f09f6a2f49ed677e4138ea801c97c25462d11330307a801f86974e75a2c585bd0c34cb"} build_url:https://queue.taskcluster.net/v1/task/ekxJUFGQRouhYelxehikiQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011651 build_url: 'https://queue.taskcluster.net/v1/task/ekxJUFGQRouhYelxehikiQ/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/0d88051692dc3cb2fb559c600a0cab9cf756ea4e813447c5108280fe1f06dc1bf50bd1509cd1cab4812a769031757ed8cc71caf3d8ed8126ae88610a539482ca", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0b2179e8f92c4b4bdfeb5e373cc2f84e352a76e4098371370c98d12521f09f6a2f49ed677e4138ea801c97c25462d11330307a801f86974e75a2c585bd0c34cb"}' ========= master_lag: 8.74 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 8 secs) (at 2016-04-15 13:42:40.755812) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-15 13:42:40.756193) ========= 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.iod25OT5aO/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.o0E7P3bGLA/Listeners TMPDIR=/var/folders/3z/sl2t9zqs0cxgfh9f4pkbmnbh00000w/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.004658 ========= master_lag: 0.78 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-15 13:42:41.540604) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-15 13:42:41.540972) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-15 13:42:41.556319) ========= ========= Total master_lag: 24.09 =========