builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0346 starttime: 1468839015.93 results: success (0) buildid: 20160718015953 builduid: 85610e5d602b41c38a2653b307561653 revision: 76bb0b29a7509fe35db3beb571fec162cc781a80 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-18 03:50:15.932652) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-18 03:50:15.933149) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-18 03:50:15.933460) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-18 03:50:15.958827) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 03:50:15.959161) ========= 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.oyYVhc0Twj/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.Pfcyrg6P5R/Listeners TMPDIR=/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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-07-18 03:50:15-- 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% 14.9M=0.001s 2016-07-18 03:50:16 (14.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.161028 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 03:50:16.142280) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 03:50:16.142633) ========= 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.oyYVhc0Twj/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.Pfcyrg6P5R/Listeners TMPDIR=/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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.020520 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 03:50:16.191625) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 03:50:16.191992) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 76bb0b29a7509fe35db3beb571fec162cc781a80 --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 76bb0b29a7509fe35db3beb571fec162cc781a80 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.oyYVhc0Twj/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.Pfcyrg6P5R/Listeners TMPDIR=/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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-07-18 03:50:16,271 truncating revision to first 12 chars 2016-07-18 03:50:16,271 Setting DEBUG logging. 2016-07-18 03:50:16,271 attempt 1/10 2016-07-18 03:50:16,271 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/76bb0b29a750?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-18 03:50:16,907 unpacking tar archive at: fx-team-76bb0b29a750/testing/mozharness/ program finished with exit code 0 elapsedTime=0.901939 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 03:50:17.115667) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-18 03:50:17.116055) ========= script_repo_revision: 76bb0b29a7509fe35db3beb571fec162cc781a80 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-18 03:50:17.116491) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-18 03:50:17.116793) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-18 03:50:17.136056) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-07-18 03:50:17.136365) ========= /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.oyYVhc0Twj/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.Pfcyrg6P5R/Listeners TMPDIR=/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 03:50:17 INFO - MultiFileLogger online at 20160718 03:50:17 in /builds/slave/test 03:50: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 03:50:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:50:17 INFO - {'append_to_log': False, 03:50:17 INFO - 'base_work_dir': '/builds/slave/test', 03:50:17 INFO - 'blob_upload_branch': 'Fx-Team', 03:50:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:50:17 INFO - 'branch': 'Fx-Team', 03:50:17 INFO - 'buildbot_json_path': 'buildprops.json', 03:50:17 INFO - 'config_files': ('talos/mac_config.py',), 03:50:17 INFO - 'default_actions': ('clobber', 03:50:17 INFO - 'read-buildbot-config', 03:50:17 INFO - 'download-and-extract', 03:50:17 INFO - 'populate-webroot', 03:50:17 INFO - 'create-virtualenv', 03:50:17 INFO - 'install', 03:50:17 INFO - 'run-tests'), 03:50:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:50:17 INFO - 'download_minidump_stackwalk': True, 03:50:17 INFO - 'download_symbols': 'ondemand', 03:50:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:50:17 INFO - 'tooltool.py': '/tools/tooltool.py', 03:50:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:50:17 INFO - '/tools/misc-python/virtualenv.py')}, 03:50:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:50:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:50:17 INFO - 'installer_path': 'installer.exe', 03:50:17 INFO - 'log_level': 'info', 03:50:17 INFO - 'log_name': 'talos', 03:50:17 INFO - 'log_to_console': True, 03:50:17 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:50:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:50:17 INFO - 'opt_config_files': (), 03:50:17 INFO - 'pip_index': False, 03:50:17 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:50:17 INFO - 'cmd': ('bash', 03:50:17 INFO - '-c', 03:50:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:50:17 INFO - 'enabled': True, 03:50:17 INFO - 'halt_on_failure': False, 03:50:17 INFO - 'name': 'check_screen_resolution'},), 03:50:17 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:50:17 INFO - 'cmd': ('bash', 03:50:17 INFO - '-c', 03:50:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:50:17 INFO - 'enabled': True, 03:50:17 INFO - 'halt_on_failure': False, 03:50:17 INFO - 'name': 'check_screen_resolution'},), 03:50:17 INFO - 'run_cmd_checks_enabled': True, 03:50:17 INFO - 'sps_profile': False, 03:50:17 INFO - 'sps_profile_interval': 0, 03:50:17 INFO - 'suite': 'dromaeojs', 03:50:17 INFO - 'system_bits': '32', 03:50:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:50:17 INFO - 'title': 't-yosemite-r7-0346', 03:50:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:50:17 INFO - 'use_talos_json': True, 03:50:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:50:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:50:17 INFO - 'work_dir': 'build'} 03:50:17 INFO - ##### 03:50:17 INFO - ##### Running clobber step. 03:50:17 INFO - ##### 03:50:17 INFO - Running pre-action listener: _resource_record_pre_action 03:50:17 INFO - Running main action method: clobber 03:50:17 INFO - rmtree: /builds/slave/test/build 03:50:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:50:18 INFO - Running post-action listener: _resource_record_post_action 03:50:18 INFO - ##### 03:50:18 INFO - ##### Finished clobber step (success) 03:50:18 INFO - ##### 03:50:18 INFO - ##### 03:50:18 INFO - ##### Running read-buildbot-config step. 03:50:18 INFO - ##### 03:50:18 INFO - Running pre-action listener: _resource_record_pre_action 03:50:18 INFO - Running main action method: read_buildbot_config 03:50:18 INFO - Using buildbot properties: 03:50:18 INFO - { 03:50:18 INFO - "project": "", 03:50:18 INFO - "product": "firefox", 03:50:18 INFO - "script_repo_revision": "production", 03:50:18 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 03:50:18 INFO - "repository": "", 03:50:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 03:50:18 INFO - "buildid": "20160718015953", 03:50:18 INFO - "pgo_build": "False", 03:50:18 INFO - "basedir": "/builds/slave/test", 03:50:18 INFO - "buildnumber": 847, 03:50:18 INFO - "slavename": "t-yosemite-r7-0346", 03:50:18 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 03:50:18 INFO - "platform": "yosemite_r7", 03:50:18 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 03:50:18 INFO - "branch": "fx-team", 03:50:18 INFO - "repo_path": "integration/fx-team", 03:50:18 INFO - "revision": "76bb0b29a7509fe35db3beb571fec162cc781a80", 03:50:18 INFO - "stage_platform": "macosx64", 03:50:18 INFO - "builduid": "85610e5d602b41c38a2653b307561653", 03:50:18 INFO - "slavebuilddir": "test" 03:50:18 INFO - } 03:50:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/fk14sDrSRz2NK8krTQ9aYA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 03:50:18 INFO - Running post-action listener: _resource_record_post_action 03:50:18 INFO - ##### 03:50:18 INFO - ##### Finished read-buildbot-config step (success) 03:50:18 INFO - ##### 03:50:18 INFO - ##### 03:50:18 INFO - ##### Running download-and-extract step. 03:50:18 INFO - ##### 03:50:18 INFO - Running pre-action listener: _resource_record_pre_action 03:50:18 INFO - Running main action method: download_and_extract 03:50:18 INFO - mkdir: /builds/slave/test/build/tests 03:50: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')]} 03:50:18 INFO - https://queue.taskcluster.net/v1/task/fk14sDrSRz2NK8krTQ9aYA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 03:50:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fk14sDrSRz2NK8krTQ9aYA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 03:50:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fk14sDrSRz2NK8krTQ9aYA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 03:50:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fk14sDrSRz2NK8krTQ9aYA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 03:50:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fk14sDrSRz2NK8krTQ9aYA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 03:50:19 INFO - Downloaded 1199 bytes. 03:50:19 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 03:50:19 INFO - Using the following test package requirements: 03:50:19 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 03:50:19 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:50:19 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 03:50:19 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:50:19 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 03:50:19 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:50:19 INFO - u'jsshell-mac.zip'], 03:50:19 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:50:19 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 03:50:19 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 03:50:19 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:50:19 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 03:50:19 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:50:19 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 03:50:19 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:50:19 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 03:50:19 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 03:50:19 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 03:50:19 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:50:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:50:19 INFO - https://queue.taskcluster.net/v1/task/fk14sDrSRz2NK8krTQ9aYA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:50:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fk14sDrSRz2NK8krTQ9aYA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 03:50:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fk14sDrSRz2NK8krTQ9aYA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 03:50:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fk14sDrSRz2NK8krTQ9aYA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 03:50:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fk14sDrSRz2NK8krTQ9aYA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:50:20 INFO - Downloaded 18835810 bytes. 03:50:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:50:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:50:23 INFO - Return code: 0 03:50:23 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 03:50:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:50:23 INFO - https://queue.taskcluster.net/v1/task/fk14sDrSRz2NK8krTQ9aYA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:50:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fk14sDrSRz2NK8krTQ9aYA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 03:50:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fk14sDrSRz2NK8krTQ9aYA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 03:50:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fk14sDrSRz2NK8krTQ9aYA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 03:50:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fk14sDrSRz2NK8krTQ9aYA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 03:50:25 INFO - Downloaded 18835810 bytes. 03:50:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:50:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:50:29 INFO - Return code: 0 03:50: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')]} 03:50:29 INFO - https://queue.taskcluster.net/v1/task/fk14sDrSRz2NK8krTQ9aYA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 03:50:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fk14sDrSRz2NK8krTQ9aYA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 03:50:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fk14sDrSRz2NK8krTQ9aYA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 03:50:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fk14sDrSRz2NK8krTQ9aYA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 03:50:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fk14sDrSRz2NK8krTQ9aYA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 03:50:30 INFO - Downloaded 14403317 bytes. 03:50:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:50:30 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 03:50:30 INFO - Return code: 0 03:50:30 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')]} 03:50:30 INFO - https://queue.taskcluster.net/v1/task/fk14sDrSRz2NK8krTQ9aYA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 03:50:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fk14sDrSRz2NK8krTQ9aYA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 03:50:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fk14sDrSRz2NK8krTQ9aYA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 03:50:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fk14sDrSRz2NK8krTQ9aYA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 03:50:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fk14sDrSRz2NK8krTQ9aYA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 03:50:33 INFO - Downloaded 112758105 bytes. 03:50:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fk14sDrSRz2NK8krTQ9aYA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 03:50:33 INFO - mkdir: /builds/slave/test/properties 03:50:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:50:33 INFO - Writing to file /builds/slave/test/properties/build_url 03:50:33 INFO - Contents: 03:50:33 INFO - build_url:https://queue.taskcluster.net/v1/task/fk14sDrSRz2NK8krTQ9aYA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 03:50:34 INFO - Running post-action listener: _resource_record_post_action 03:50:34 INFO - Running post-action listener: set_extra_try_arguments 03:50:34 INFO - ##### 03:50:34 INFO - ##### Finished download-and-extract step (success) 03:50:34 INFO - ##### 03:50:34 INFO - ##### 03:50:34 INFO - ##### Running populate-webroot step. 03:50:34 INFO - ##### 03:50:34 INFO - Running pre-action listener: _resource_record_pre_action 03:50:34 INFO - Running main action method: populate_webroot 03:50:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:50:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:50:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:50:34 INFO - '--tppagecycles', 03:50:34 INFO - '7'], 03:50:34 INFO - 'tests': ['tsvgm']}}, 03:50:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:50:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:50:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:50:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:50:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:50:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:50:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:50:34 INFO - 'talos_options': ['--disable-e10s'], 03:50:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:50:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:50:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:50:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:50:34 INFO - 'talos_options': ['--disable-e10s'], 03:50:34 INFO - 'tests': ['damp', 'tps']}, 03:50:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:50:34 INFO - 'tests': ['damp', 'tps']}, 03:50:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:50:34 INFO - 'tests': ['dromaeo_dom']}, 03:50:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:50:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:50:34 INFO - 'tests': ['basic_compositor_video']}, 03:50:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:50:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:50:34 INFO - 'tests': ['a11yr', 03:50:34 INFO - 'ts_paint', 03:50:34 INFO - 'tpaint', 03:50:34 INFO - 'sessionrestore', 03:50:34 INFO - 'sessionrestore_no_auto_restore', 03:50:34 INFO - 'tabpaint']}, 03:50:34 INFO - 'other-e10s': {'tests': ['a11yr', 03:50:34 INFO - 'ts_paint', 03:50:34 INFO - 'tpaint', 03:50:34 INFO - 'sessionrestore', 03:50:34 INFO - 'sessionrestore_no_auto_restore', 03:50:34 INFO - 'tabpaint']}, 03:50:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:50:34 INFO - 'ts_paint', 03:50:34 INFO - 'tpaint', 03:50:34 INFO - 'sessionrestore', 03:50:34 INFO - 'sessionrestore_no_auto_restore', 03:50:34 INFO - 'tabpaint']}, 03:50:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:50:34 INFO - 'ts_paint', 03:50:34 INFO - 'tpaint', 03:50:34 INFO - 'sessionrestore', 03:50:34 INFO - 'sessionrestore_no_auto_restore', 03:50:34 INFO - 'tabpaint']}, 03:50:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:50:34 INFO - 'tests': ['a11yr', 03:50:34 INFO - 'ts_paint', 03:50:34 INFO - 'tpaint', 03:50:34 INFO - 'sessionrestore', 03:50:34 INFO - 'sessionrestore_no_auto_restore', 03:50:34 INFO - 'tabpaint']}, 03:50:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:50:34 INFO - 'tests': ['a11yr', 03:50:34 INFO - 'ts_paint', 03:50:34 INFO - 'tpaint', 03:50:34 INFO - 'sessionrestore', 03:50:34 INFO - 'sessionrestore_no_auto_restore', 03:50:34 INFO - 'tabpaint']}, 03:50:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:50:34 INFO - 'tests': ['tsvgx', 03:50:34 INFO - 'tsvgr_opacity', 03:50:34 INFO - 'tart', 03:50:34 INFO - 'tscrollx', 03:50:34 INFO - 'cart']}, 03:50:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:50:34 INFO - 'tsvgr_opacity', 03:50:34 INFO - 'tart', 03:50:34 INFO - 'tscrollx', 03:50:34 INFO - 'cart']}, 03:50:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:50:34 INFO - 'talos_options': ['--disable-e10s'], 03:50:34 INFO - 'tests': ['tp5o']}, 03:50:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:50:34 INFO - 'tests': ['tp5o']}, 03:50:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:50:34 INFO - 'talos_options': ['--disable-e10s', 03:50:34 INFO - '--xperf_path', 03:50:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:50:34 INFO - 'tests': ['tp5n']}, 03:50:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:50:34 INFO - 'talos_options': ['--xperf_path', 03:50:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:50:34 INFO - 'tests': ['tp5n']}}, 03:50:34 INFO - 'talos.zip': {'path': '', 03:50:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:50:34 INFO - Running post-action listener: _resource_record_post_action 03:50:34 INFO - ##### 03:50:34 INFO - ##### Finished populate-webroot step (success) 03:50:34 INFO - ##### 03:50:34 INFO - ##### 03:50:34 INFO - ##### Running create-virtualenv step. 03:50:34 INFO - ##### 03:50:34 INFO - Running pre-action listener: _resource_record_pre_action 03:50:34 INFO - Running main action method: create_virtualenv 03:50:34 INFO - Creating virtualenv /builds/slave/test/build/venv 03:50:34 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 03:50:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:50:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:50:34 INFO - Using real prefix '/tools/python27' 03:50:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:50:35 INFO - Installing distribute.............................................................................................................................................................................................done. 03:50:38 INFO - Installing pip.................done. 03:50:38 INFO - Return code: 0 03:50:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:50:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:50:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:50:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:50:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:50:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:50:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:50:38 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 0x10a53ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5d3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba43d8d070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4a2d30>, '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 0x10a207db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a49ab20>, '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.oyYVhc0Twj/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.Pfcyrg6P5R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 03:50:38 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 03:50:38 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 03:50:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oyYVhc0Twj/Render', 03:50:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:50:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:50:38 INFO - 'HOME': '/Users/cltbld', 03:50:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:50:38 INFO - 'LOGNAME': 'cltbld', 03:50:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:50:38 INFO - 'MOZ_NO_REMOTE': '1', 03:50:38 INFO - 'NO_EM_RESTART': '1', 03:50:38 INFO - 'PAGER': '/bin/cat', 03:50:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:50:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:50:38 INFO - 'PWD': '/builds/slave/test', 03:50:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:50:38 INFO - 'SHELL': '/bin/bash', 03:50:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pfcyrg6P5R/Listeners', 03:50:38 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 03:50:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:50:38 INFO - 'USER': 'cltbld', 03:50:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:50:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:50:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:50:38 INFO - 'XPC_FLAGS': '0x0', 03:50:38 INFO - 'XPC_SERVICE_NAME': '0', 03:50:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:50:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:50:38 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 03:50:38 INFO - Cleaning up... 03:50:38 INFO - Return code: 0 03:50:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:50:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:50:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:50:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:50:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:50:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:50:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:50:38 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>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a53ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5d3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba43d8d070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4a2d30>, '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 0x10a207db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a49ab20>, '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.oyYVhc0Twj/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.Pfcyrg6P5R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 03:50:38 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>=3.1.1'] in /builds/slave/test/build 03:50:38 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>=3.1.1 03:50:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oyYVhc0Twj/Render', 03:50:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:50:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:50:38 INFO - 'HOME': '/Users/cltbld', 03:50:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:50:38 INFO - 'LOGNAME': 'cltbld', 03:50:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:50:38 INFO - 'MOZ_NO_REMOTE': '1', 03:50:38 INFO - 'NO_EM_RESTART': '1', 03:50:38 INFO - 'PAGER': '/bin/cat', 03:50:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:50:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:50:38 INFO - 'PWD': '/builds/slave/test', 03:50:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:50:38 INFO - 'SHELL': '/bin/bash', 03:50:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pfcyrg6P5R/Listeners', 03:50:38 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 03:50:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:50:38 INFO - 'USER': 'cltbld', 03:50:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:50:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:50:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:50:38 INFO - 'XPC_FLAGS': '0x0', 03:50:38 INFO - 'XPC_SERVICE_NAME': '0', 03:50:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:50:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:50:39 INFO - Downloading/unpacking psutil>=3.1.1 03:50:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:50:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:50:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:50:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:50:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:50:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:50:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:50:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:50:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:50:41 INFO - Installing collected packages: psutil 03:50:41 INFO - Running setup.py install for psutil 03:50:42 INFO - building 'psutil._psutil_osx' extension 03:50:42 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 03:50:42 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 03:50:42 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 03:50:42 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 03:50:42 INFO - building 'psutil._psutil_posix' extension 03:50:42 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 03:50:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:50:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:50:42 INFO - ^ 03:50:42 INFO - 1 warning generated. 03:50:42 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 03:50:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:50:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:50:42 INFO - Successfully installed psutil 03:50:42 INFO - Cleaning up... 03:50:42 INFO - Return code: 0 03:50:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:50:42 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')]} 03:50:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:50:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:50:42 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')]} 03:50:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:50:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:50:42 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.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a53ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5d3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba43d8d070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4a2d30>, '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 0x10a207db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a49ab20>, '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.oyYVhc0Twj/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.Pfcyrg6P5R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 03:50:42 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.3'] in /builds/slave/test/build 03:50:42 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.3 03:50:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oyYVhc0Twj/Render', 03:50:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:50:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:50:42 INFO - 'HOME': '/Users/cltbld', 03:50:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:50:42 INFO - 'LOGNAME': 'cltbld', 03:50:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:50:42 INFO - 'MOZ_NO_REMOTE': '1', 03:50:42 INFO - 'NO_EM_RESTART': '1', 03:50:42 INFO - 'PAGER': '/bin/cat', 03:50:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:50:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:50:42 INFO - 'PWD': '/builds/slave/test', 03:50:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:50:42 INFO - 'SHELL': '/bin/bash', 03:50:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pfcyrg6P5R/Listeners', 03:50:42 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 03:50:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:50:42 INFO - 'USER': 'cltbld', 03:50:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:50:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:50:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:50:42 INFO - 'XPC_FLAGS': '0x0', 03:50:42 INFO - 'XPC_SERVICE_NAME': '0', 03:50:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:50:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:50:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:50:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:50:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:50:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:50:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:50:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:50:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:50:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:50:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:50:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:50:45 INFO - Installing collected packages: mozsystemmonitor 03:50:45 INFO - Running setup.py install for mozsystemmonitor 03:50:45 INFO - Successfully installed mozsystemmonitor 03:50:45 INFO - Cleaning up... 03:50:45 INFO - Return code: 0 03:50:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:50: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')]} 03:50:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:50:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:50: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')]} 03:50:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:50:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:50: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', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a53ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5d3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba43d8d070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4a2d30>, '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 0x10a207db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a49ab20>, '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.oyYVhc0Twj/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.Pfcyrg6P5R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 03:50: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 03:50: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 jsonschema==2.5.1 03:50:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oyYVhc0Twj/Render', 03:50:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:50:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:50:45 INFO - 'HOME': '/Users/cltbld', 03:50:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:50:45 INFO - 'LOGNAME': 'cltbld', 03:50:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:50:45 INFO - 'MOZ_NO_REMOTE': '1', 03:50:45 INFO - 'NO_EM_RESTART': '1', 03:50:45 INFO - 'PAGER': '/bin/cat', 03:50:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:50:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:50:45 INFO - 'PWD': '/builds/slave/test', 03:50:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:50:45 INFO - 'SHELL': '/bin/bash', 03:50:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pfcyrg6P5R/Listeners', 03:50:45 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 03:50:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:50:45 INFO - 'USER': 'cltbld', 03:50:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:50:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:50:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:50:45 INFO - 'XPC_FLAGS': '0x0', 03:50:45 INFO - 'XPC_SERVICE_NAME': '0', 03:50:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:50:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:50:46 INFO - Downloading/unpacking jsonschema==2.5.1 03:50:46 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 03:50:46 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 03:50:46 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 03:50:46 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 03:50:46 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 03:50:46 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 03:50:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:50:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:50: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 03:50: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 03:50: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 03:50: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 03:50: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 03:50: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 03:50:48 INFO - Downloading functools32-3.2.3-2.tar.gz 03:50:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:50:49 INFO - warning: no files found matching '*.txt' 03:50:49 INFO - no previously-included directories found matching 'build' 03:50:49 INFO - no previously-included directories found matching 'dist' 03:50:49 INFO - no previously-included directories found matching '.git*' 03:50:49 INFO - Installing collected packages: jsonschema, functools32 03:50:49 INFO - Running setup.py install for functools32 03:50:49 INFO - warning: no files found matching '*.txt' 03:50:49 INFO - no previously-included directories found matching 'build' 03:50:49 INFO - no previously-included directories found matching 'dist' 03:50:49 INFO - no previously-included directories found matching '.git*' 03:50:49 INFO - Successfully installed jsonschema functools32 03:50:49 INFO - Cleaning up... 03:50:49 INFO - Return code: 0 03:50:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:50:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:50:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:50:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:50:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:50:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:50:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:50:49 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 0x10a53ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5d3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba43d8d070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4a2d30>, '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 0x10a207db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a49ab20>, '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.oyYVhc0Twj/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.Pfcyrg6P5R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 03:50:49 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 03:50:49 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 03:50:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oyYVhc0Twj/Render', 03:50:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:50:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:50:49 INFO - 'HOME': '/Users/cltbld', 03:50:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:50:49 INFO - 'LOGNAME': 'cltbld', 03:50:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:50:49 INFO - 'MOZ_NO_REMOTE': '1', 03:50:49 INFO - 'NO_EM_RESTART': '1', 03:50:49 INFO - 'PAGER': '/bin/cat', 03:50:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:50:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:50:49 INFO - 'PWD': '/builds/slave/test', 03:50:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:50:49 INFO - 'SHELL': '/bin/bash', 03:50:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pfcyrg6P5R/Listeners', 03:50:49 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 03:50:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:50:49 INFO - 'USER': 'cltbld', 03:50:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:50:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:50:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:50:49 INFO - 'XPC_FLAGS': '0x0', 03:50:49 INFO - 'XPC_SERVICE_NAME': '0', 03:50:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:50:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:50:49 INFO - Downloading/unpacking blobuploader==1.2.4 03:50:49 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 03:50:49 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 03:50:49 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 03:50:49 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 03:50:49 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 03:50:49 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 03:50:52 INFO - Downloading blobuploader-1.2.4.tar.gz 03:50:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:50:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:50:52 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 03:50:52 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 03:50:52 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 03:50:52 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 03:50:52 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 03:50:52 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 03:50:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:50:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:50:52 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 03:50:52 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 03:50:52 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 03:50:52 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 03:50:52 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 03:50:52 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 03:50:53 INFO - Downloading docopt-0.6.1.tar.gz 03:50:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:50:53 INFO - Installing collected packages: blobuploader, requests, docopt 03:50:53 INFO - Running setup.py install for blobuploader 03:50:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:50:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:50:53 INFO - Running setup.py install for requests 03:50:54 INFO - Running setup.py install for docopt 03:50:54 INFO - Successfully installed blobuploader requests docopt 03:50:54 INFO - Cleaning up... 03:50:54 INFO - Return code: 0 03:50:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:50:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:50:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:50:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:50:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:50:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:50:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:50:54 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 0x10a53ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5d3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba43d8d070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4a2d30>, '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 0x10a207db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a49ab20>, '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.oyYVhc0Twj/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.Pfcyrg6P5R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 03:50:54 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 03:50:54 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 03:50:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oyYVhc0Twj/Render', 03:50:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:50:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:50:54 INFO - 'HOME': '/Users/cltbld', 03:50:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:50:54 INFO - 'LOGNAME': 'cltbld', 03:50:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:50:54 INFO - 'MOZ_NO_REMOTE': '1', 03:50:54 INFO - 'NO_EM_RESTART': '1', 03:50:54 INFO - 'PAGER': '/bin/cat', 03:50:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:50:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:50:54 INFO - 'PWD': '/builds/slave/test', 03:50:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:50:54 INFO - 'SHELL': '/bin/bash', 03:50:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pfcyrg6P5R/Listeners', 03:50:54 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 03:50:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:50:54 INFO - 'USER': 'cltbld', 03:50:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:50:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:50:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:50:54 INFO - 'XPC_FLAGS': '0x0', 03:50:54 INFO - 'XPC_SERVICE_NAME': '0', 03:50:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:50:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:50:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:50:54 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-1BXM4m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:50:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:50:54 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-NpNtLz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:50:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:50:54 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-Qgm13D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:50:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:50:54 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-je5QTu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:50:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:50:54 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-PI2lbc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:50:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:50:55 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-qj1Ike-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:50:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:50:55 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-PDTvuV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:50:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:50:55 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-J3Z2Lo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:50:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:50:55 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-bDHzGc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:50:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:50:55 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-kXWaBK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:50:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:50:55 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-StF5cg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:50:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:50:55 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-8TTLkH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:50:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:50:55 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-ywr9be-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:50:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:50:55 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-zqbsAr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:50:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:50:55 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-LPiqcT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:50:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:50:56 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-Rq6Qpv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:50:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:50:56 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-ZUC1O5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:50:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:50:56 INFO - Running setup.py install for manifestparser 03:50:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:50:56 INFO - Running setup.py install for mozcrash 03:50:56 INFO - Running setup.py install for mozdebug 03:50:56 INFO - Running setup.py install for mozdevice 03:50:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:50:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:50:56 INFO - Running setup.py install for mozfile 03:50:57 INFO - Running setup.py install for mozhttpd 03:50:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:50:57 INFO - Running setup.py install for mozinfo 03:50:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:50:57 INFO - Running setup.py install for mozInstall 03:50:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:50:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:50:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:50:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:50:57 INFO - Running setup.py install for mozleak 03:50:57 INFO - Running setup.py install for mozlog 03:50:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:50:57 INFO - Running setup.py install for moznetwork 03:50:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:50:57 INFO - Running setup.py install for mozprocess 03:50:58 INFO - Running setup.py install for mozprofile 03:50:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:50:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:50:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:50:58 INFO - Running setup.py install for mozrunner 03:50:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:50:58 INFO - Running setup.py install for mozscreenshot 03:50:58 INFO - Running setup.py install for moztest 03:50:58 INFO - Running setup.py install for mozversion 03:50:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:50:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:50:58 INFO - Cleaning up... 03:50:58 INFO - Return code: 0 03:50:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:50:58 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')]} 03:50:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:50:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:50:58 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')]} 03:50:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:50:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:50:58 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 0x10a53ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5d3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba43d8d070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4a2d30>, '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 0x10a207db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a49ab20>, '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.oyYVhc0Twj/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.Pfcyrg6P5R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 03:50:58 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 03:50:58 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 03:50:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oyYVhc0Twj/Render', 03:50:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:50:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:50:58 INFO - 'HOME': '/Users/cltbld', 03:50:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:50:58 INFO - 'LOGNAME': 'cltbld', 03:50:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:50:58 INFO - 'MOZ_NO_REMOTE': '1', 03:50:58 INFO - 'NO_EM_RESTART': '1', 03:50:58 INFO - 'PAGER': '/bin/cat', 03:50:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:50:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:50:58 INFO - 'PWD': '/builds/slave/test', 03:50:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:50:58 INFO - 'SHELL': '/bin/bash', 03:50:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pfcyrg6P5R/Listeners', 03:50:58 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 03:50:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:50:58 INFO - 'USER': 'cltbld', 03:50:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:50:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:50:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:50:58 INFO - 'XPC_FLAGS': '0x0', 03:50:58 INFO - 'XPC_SERVICE_NAME': '0', 03:50:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:50:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:50:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:50:59 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-94KEna-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:50:59 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)) 03:50:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:50:59 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-k_aKpQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:50:59 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)) 03:50:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:50:59 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-CKYYg3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:50:59 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)) 03:50:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:50:59 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-xA63mp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:50:59 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)) 03:50:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:50:59 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-DWlvfe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:50:59 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)) 03:50:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:50:59 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-kjGz9w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:50:59 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)) 03:50:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:50:59 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-AwTxcx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:50:59 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)) 03:50:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:50:59 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-pbFauf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:50: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)) 03:50:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:50:59 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-VEdTHL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:51:00 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)) 03:51:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:51:00 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-zHOQ0J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:51:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 03:51:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:51:00 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-_GWtqt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:51:00 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)) 03:51:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:51:00 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-mAS3ID-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:51:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 03:51:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:51:00 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-L3XTUQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:51:00 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)) 03:51:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:51:00 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-iNGnMb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:51:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 03:51:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:51:00 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-eHCfAL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:51:00 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)) 03:51:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:51:00 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-g_CfWS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:51:00 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)) 03:51:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:51:00 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-jRdMIn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:51:00 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)) 03:51:00 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)) 03:51:00 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)) 03:51:00 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)) 03:51:00 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)) 03:51:00 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)) 03:51:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:51:00 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 03:51:00 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 03:51:00 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 03:51:00 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 03:51:00 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 03:51:00 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 03:51:03 INFO - Downloading blessings-1.6.tar.gz 03:51:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:51:03 INFO - Installing collected packages: blessings 03:51:03 INFO - Running setup.py install for blessings 03:51:03 INFO - Successfully installed blessings 03:51:03 INFO - Cleaning up... 03:51:03 INFO - Return code: 0 03:51:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:51:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:51:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:51:03 INFO - Reading from file tmpfile_stdout 03:51:03 INFO - Current package versions: 03:51:03 INFO - blessings == 1.6 03:51:03 INFO - blobuploader == 1.2.4 03:51:03 INFO - docopt == 0.6.1 03:51:03 INFO - functools32 == 3.2.3-2 03:51:03 INFO - jsonschema == 2.5.1 03:51:03 INFO - manifestparser == 1.1 03:51:03 INFO - mozInstall == 1.12 03:51:03 INFO - mozcrash == 0.17 03:51:03 INFO - mozdebug == 0.1 03:51:03 INFO - mozdevice == 0.48 03:51:03 INFO - mozfile == 1.2 03:51:03 INFO - mozhttpd == 0.7 03:51:03 INFO - mozinfo == 0.9 03:51:03 INFO - mozleak == 0.1 03:51:03 INFO - mozlog == 3.2 03:51:03 INFO - moznetwork == 0.27 03:51:03 INFO - mozprocess == 0.23 03:51:03 INFO - mozprofile == 0.28 03:51:03 INFO - mozrunner == 6.12 03:51:03 INFO - mozscreenshot == 0.1 03:51:03 INFO - mozsystemmonitor == 0.3 03:51:03 INFO - moztest == 0.7 03:51:03 INFO - mozversion == 1.4 03:51:03 INFO - psutil == 3.1.1 03:51:03 INFO - requests == 1.2.3 03:51:03 INFO - wsgiref == 0.1.2 03:51:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:51: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')]} 03:51:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:51:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:51: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')]} 03:51:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:51:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:51: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 0x10a53ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5d3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba43d8d070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4a2d30>, '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 0x10a207db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a49ab20>, '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.oyYVhc0Twj/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.Pfcyrg6P5R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 03:51: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 03:51: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 03:51:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oyYVhc0Twj/Render', 03:51:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:51:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:51:03 INFO - 'HOME': '/Users/cltbld', 03:51:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:51:03 INFO - 'LOGNAME': 'cltbld', 03:51:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:51:03 INFO - 'MOZ_NO_REMOTE': '1', 03:51:03 INFO - 'NO_EM_RESTART': '1', 03:51:03 INFO - 'PAGER': '/bin/cat', 03:51:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:51:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:51:03 INFO - 'PWD': '/builds/slave/test', 03:51:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:51:03 INFO - 'SHELL': '/bin/bash', 03:51:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pfcyrg6P5R/Listeners', 03:51:03 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 03:51:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:51:03 INFO - 'USER': 'cltbld', 03:51:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:51:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:51:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:51:03 INFO - 'XPC_FLAGS': '0x0', 03:51:03 INFO - 'XPC_SERVICE_NAME': '0', 03:51:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:51:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:51:04 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)) 03:51:04 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)) 03:51:04 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)) 03:51:04 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)) 03:51:04 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)) 03:51:04 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)) 03:51:04 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)) 03:51:04 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)) 03:51:04 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)) 03:51:04 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)) 03:51:04 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)) 03:51:04 INFO - Cleaning up... 03:51:04 INFO - Return code: 0 03:51:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:51:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:51:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:51:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:51:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:51:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:51:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:51:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a53ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a5d3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fba43d8d070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4a2d30>, '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 0x10a207db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a49ab20>, '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.oyYVhc0Twj/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.Pfcyrg6P5R/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 03:51:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:51:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:51:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oyYVhc0Twj/Render', 03:51:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:51:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:51:04 INFO - 'HOME': '/Users/cltbld', 03:51:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:51:04 INFO - 'LOGNAME': 'cltbld', 03:51:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:51:04 INFO - 'MOZ_NO_REMOTE': '1', 03:51:04 INFO - 'NO_EM_RESTART': '1', 03:51:04 INFO - 'PAGER': '/bin/cat', 03:51:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:51:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:51:04 INFO - 'PWD': '/builds/slave/test', 03:51:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:51:04 INFO - 'SHELL': '/bin/bash', 03:51:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pfcyrg6P5R/Listeners', 03:51:04 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 03:51:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:51:04 INFO - 'USER': 'cltbld', 03:51:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:51:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:51:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:51:04 INFO - 'XPC_FLAGS': '0x0', 03:51:04 INFO - 'XPC_SERVICE_NAME': '0', 03:51:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:51:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:51:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:51:04 INFO - Cleaning up... 03:51:04 INFO - Return code: 0 03:51:04 INFO - Running post-action listener: _resource_record_post_action 03:51:04 INFO - Running post-action listener: _start_resource_monitoring 03:51:04 INFO - Starting resource monitoring. 03:51:04 INFO - ##### 03:51:04 INFO - ##### Finished create-virtualenv step (success) 03:51:04 INFO - ##### 03:51:04 INFO - ##### 03:51:04 INFO - ##### Running install step. 03:51:04 INFO - ##### 03:51:04 INFO - Running pre-action listener: _resource_record_pre_action 03:51:04 INFO - Running main action method: install 03:51:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:51:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:51:04 INFO - Reading from file tmpfile_stdout 03:51:04 INFO - Detecting whether we're running mozinstall >=1.0... 03:51:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:51:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:51:04 INFO - Reading from file tmpfile_stdout 03:51:04 INFO - Output received: 03:51:04 INFO - Usage: mozinstall [options] installer 03:51:04 INFO - Options: 03:51:04 INFO - -h, --help show this help message and exit 03:51:04 INFO - -d DEST, --destination=DEST 03:51:04 INFO - Directory to install application into. [default: 03:51:04 INFO - "/builds/slave/test"] 03:51:04 INFO - --app=APP Application being installed. [default: firefox] 03:51:04 INFO - mkdir: /builds/slave/test/build/application 03:51:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:51:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 03:51:39 INFO - Reading from file tmpfile_stdout 03:51:39 INFO - Output received: 03:51:39 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:51:39 INFO - Running post-action listener: _resource_record_post_action 03:51:39 INFO - ##### 03:51:39 INFO - ##### Finished install step (success) 03:51:39 INFO - ##### 03:51:39 INFO - ##### 03:51:39 INFO - ##### Running run-tests step. 03:51:39 INFO - ##### 03:51:39 INFO - Running pre-action listener: _resource_record_pre_action 03:51:39 INFO - Running main action method: run_tests 03:51:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:51:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:51:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:51:39 INFO - 2016-07-18 03:51:39.922 screenresolution[1939:14902] starting screenresolution argv=screenresolution get 03:51:39 INFO - 2016-07-18 03:51:39.925 screenresolution[1939:14902] Display 0: 1600x1200x32@60 03:51:39 INFO - 2016-07-18 03:51:39.931 screenresolution[1940:14904] starting screenresolution argv=screenresolution list 03:51:39 INFO - Available Modes on Display 0 03:51:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:51:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:51:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:51:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:51:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:51:40 INFO - Intel Iris: 03:51:40 INFO - Chipset Model: Intel Iris 03:51:40 INFO - Type: GPU 03:51:40 INFO - Bus: Built-In 03:51:40 INFO - VRAM (Dynamic, Max): 1536 MB 03:51:40 INFO - Vendor: Intel (0x8086) 03:51:40 INFO - Device ID: 0x0a2e 03:51:40 INFO - Revision ID: 0x0009 03:51:40 INFO - Displays: 03:51:40 INFO - Display: 03:51:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:51:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:51:40 INFO - Display Serial Number: 1600x1200 60 03:51:40 INFO - Main Display: Yes 03:51:40 INFO - Mirror: Off 03:51:40 INFO - Online: Yes 03:51:40 INFO - Rotation: Supported 03:51:40 INFO - Return code: 0 03:51:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:51:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:51:40 INFO - Python 2.7.3 03:51:40 INFO - Return code: 0 03:51:40 INFO - grabbing minidump binary from tooltool 03:51: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')]} 03:51:40 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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a4a2d30>, '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 0x10a207db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a49ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:51:40 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 03:51:40 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 03:51:40 INFO - Calling ['/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'] with output_timeout 600 03:51:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:51:40 INFO - Return code: 0 03:51:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:51:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:51:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:51:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:51:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:51:40 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/fk14sDrSRz2NK8krTQ9aYA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0346', '--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 03:51:40 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/fk14sDrSRz2NK8krTQ9aYA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0346 --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 03:51:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oyYVhc0Twj/Render', 03:51:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:51:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:51:40 INFO - 'HOME': '/Users/cltbld', 03:51:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:51:40 INFO - 'LOGNAME': 'cltbld', 03:51:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:51:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:51:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:51:40 INFO - 'MOZ_NO_REMOTE': '1', 03:51:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:51:40 INFO - 'NO_EM_RESTART': '1', 03:51:40 INFO - 'PAGER': '/bin/cat', 03:51:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:51:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:51:40 INFO - 'PWD': '/builds/slave/test', 03:51:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:51:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:51:40 INFO - 'SHELL': '/bin/bash', 03:51:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pfcyrg6P5R/Listeners', 03:51:40 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 03:51:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:51:40 INFO - 'USER': 'cltbld', 03:51:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:51:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:51:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:51:40 INFO - 'XPC_FLAGS': '0x0', 03:51:40 INFO - 'XPC_SERVICE_NAME': '0', 03:51:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:51:40 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/fk14sDrSRz2NK8krTQ9aYA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0346', '--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 03:51:40 INFO - mozversion application_buildid: 20160718015953 03:51:40 INFO - mozversion application_changeset: 76bb0b29a7509fe35db3beb571fec162cc781a80 03:51:40 INFO - mozversion application_display_name: Nightly 03:51:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:51:40 INFO - mozversion application_name: Firefox 03:51:40 INFO - mozversion application_remotingname: firefox 03:51:40 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 03:51:40 INFO - mozversion application_vendor: Mozilla 03:51:40 INFO - mozversion application_version: 50.0a1 03:51:40 INFO - mozversion platform_buildid: 20160718015953 03:51:40 INFO - mozversion platform_changeset: 76bb0b29a7509fe35db3beb571fec162cc781a80 03:51:40 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 03:51:40 INFO - mozversion platform_version: 50.0a1 03:51:40 INFO - using testdate: 1468839100 03:51:40 INFO - actual date: 1468839100 03:51:40 INFO - starting webserver on 'localhost:52623' 03:51:40 INFO - SUITE-START | Running 2 tests 03:51:40 INFO - TEST-START | dromaeo_css 03:51:40 INFO - operating with platform_type : mac_ 03:51:40 INFO - Initialising browser for dromaeo_css test... 03:51:40 INFO - TEST-INFO | started process 1950 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/tmpUUZr4U/profile http://localhost:52623/getInfo.html) 03:51:42 INFO - PROCESS | 1950 | JavaScript error: , line 0: uncaught exception: undefined 03:51:42 INFO - PROCESS | 1950 | __metrics Screen width/height:1600/1200 03:51:42 INFO - PROCESS | 1950 | colorDepth:24 03:51:42 INFO - PROCESS | 1950 | Browser inner width/height: 1024/689 03:51:42 INFO - PROCESS | 1950 | __metrics 03:51:42 INFO - PROCESS | 1950 | 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" 03:51:42 INFO - PROCESS | 1950 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:51:43 INFO - TEST-INFO | 1950: exit 0 03:51:43 INFO - Browser initialized. 03:51:43 INFO - Running cycle 1/1 for dromaeo_css test... 03:51:43 INFO - TEST-INFO | started process 1952 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/tmpUUZr4U/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:51:55 INFO - PROCESS | 1952 | RSS: Main: 245047296 03:51:55 INFO - PROCESS | 1952 | 03:52:46 INFO - PROCESS | 1952 | 1468839166919 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] 03:54:58 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:52623/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52623/tests/dromaeo/cssquery-ext.html) 03:54:59 INFO - PROCESS | 1952 | RSS: Main: 349597696 03:54:59 INFO - PROCESS | 1952 | 03:58:02 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:52623/tests/dromaeo/cssquery-ext.html (next: http://localhost:52623/tests/dromaeo/cssquery-jquery.html) 03:58:02 INFO - PROCESS | 1952 | RSS: Main: 371470336 03:58:02 INFO - PROCESS | 1952 | 04:01:05 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:52623/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52623/tests/dromaeo/cssquery-mootools.html) 04:01:05 INFO - PROCESS | 1952 | RSS: Main: 373522432 04:01:05 INFO - PROCESS | 1952 | 04:04:08 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:52623/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52623/tests/dromaeo/cssquery-prototype.html) 04:04:08 INFO - PROCESS | 1952 | RSS: Main: 373280768 04:04:08 INFO - PROCESS | 1952 | 04:07:12 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:52623/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52623/tests/dromaeo/cssquery-yui.html) 04:07:12 INFO - PROCESS | 1952 | RSS: Main: 337858560 04:07:12 INFO - PROCESS | 1952 | 04:10:15 INFO - PROCESS | 1952 | Cycle 1(1): loaded http://localhost:52623/tests/dromaeo/cssquery-yui.html (next: http://localhost:52623/tests/dromaeo/cssquery-dojo.html) 04:10:16 INFO - PROCESS | 1952 | RSS: Main: 382812160 04:10:16 INFO - PROCESS | 1952 | 04:10:16 INFO - PROCESS | 1952 | __start_tp_report 04:10:16 INFO - PROCESS | 1952 | _x_x_mozilla_page_load 04:10:16 INFO - PROCESS | 1952 | _x_x_mozilla_page_load_details 04:10:16 INFO - PROCESS | 1952 | |i|pagename|runs| 04:10:16 INFO - PROCESS | 1952 | |0;dojo.html;3346.431106711859;3358.9160270993207;3380.915477113072;3416.436288012478;3449.103233159379;5598.888022239553;5618.971905140474;5656.943430565693;5698.116791897258;5733.770649174034;4863.489333619972;5643.520300774439;5707.972564938309;5759.10733836255;5821.56338274629;5113.463086375933;5124.308218390525;5194.688318700871;5205.427402985669;5291.947080529203;5283.154695248761;5289.100852855024;5308.70802105884;5326.334208223972;5354.598405119612;5062.215356619693;5151.407588127376;5153.149730294497;5211.739413029352;5233.293505376782;5139.408967968694;5168.457311982225;5192.844214673566;5202;5205.349331333583;5088.287639730441;5141;5166.07010738067;5228.346456692913;5264.710440925747;5680.034394153004;5690.430956904318;5704.657720536781;5749.511291540224;5768.961586914354;6042.395760423967;6115.052166914135;6233.189685340877;6239.906401403982;6257.906131408033;6077.816817056593;6133.938660613381;6167.982282923313;6234.968825155891;6256.311805701369;5764;5781.942180578225;5783.190353350492;5787.160861675034;5795.565332600072;5156.948430515722;6295.842603934938;6296.5592408531875;6330.905036424458;6341.936580634135;6077.453029227391;6096.677875983316;6110.686202466491;6164.198654174928;6187.288461826857;6263.154474145932;6280.717367718464;6285.622862628257;6292.559520833498;6296.716647750863;6017;6104.298005729309;6225.346338634468;6227.346128656426;6244.0321750128805;6037.275526936795;6073.240844894388;6106;6148.9692551537855;6166.907496387557;4859.173940430064;4864.440589332201;4865.318855360252;4870.780814863339;4914.975425122923;4369.2572262714775;4397.1865204937185;4398.8020539075815;4419.823207071746;4430.82276708928;5675.77296908119;5695.060315048054;5736.68448235351;5761.193432919395;5848.093545500455;6221.2845522764555;6266.467350275187;6289.654069026156;6297.244330680256;6308.337624549448;5613.382527921925;5644.74598643062;5661.377248502661;5672.234248376499;5674.375818659944;5311.336082989626;5341.813036543703;5348.3849357323625;5369.892602147936;5407.675539467566;6287.937120628827;6307.400796924285;6311.274203466476;6329.272133704683;6334.176557047646;5969.403059693996;6093.695315234132;6153.507719382349;6209.503239740719;6232.158658581034;6294.464970477561;6302.621842689453;6308.936910630835;6310.021946598285;6312.3056463788025;6075.969620151871;6139.293981192041;6163.876722465615;6173.41352571505;6196.566240363131;6033.668148251888;6098.220442830473;6135.601185922901;6251.812445626639;6295.213098362705;5006.849794506171;6067.787627433018;6069.089636554376;6099.542534309856;6129.570930035033;4568.70303430276;6061.15143879866;6061.454469097626;6071.301800293023;6080.513558915188;5649.943500565106;5759.280089988752;5913.940860591511;5924.6149000314845;5933.703314834154;6106.49633650219;6116.27490396663;6123.984276525883;6125.9500109712835;6133.877322453615;5913.91129133072;5916.3492015877355;5917.201177840936;5928.081147422156;5928.140419639214 04:10:16 INFO - PROCESS | 1952 | |1;ext.html;484.3678998906428;522.4122861780497;535.0529562674069;554.7475898466197;556.2323992889811;30538.694613053864;32093.839530802343;32933.50599741003;42728.78635606825;42865.7856710716;44380;44412.3338149928;44441.8889527762;44539.77730111353;44728.55271447285;9776.462294573814;9958;9980.151687106569;9989.900100999006;10001.899981000206;8860.468371897674;9040;9040.547972601344;9202.171804537591;9228;10475.371477711324;12084.154109212359;12099.939500302486;12369.938150309235;12395.81406278902;12110.273383596968;12200.328981905992;12202.328871912041;12205.694857628541;12211.572594959134;8840.646374145026;11705.882941170565;11999;12074.335911524862;12165.452554635063;42138.1572368554;42529.57470425318;42550.78724606388;42613.36079958803;42710.14579708388;3450.706689931359;3464.2898205867737;3607.2244467439627;3685.152414944551;3813.3326667833294;31327.37345253105;31880.840595797104;32465;32583.51124733131;32843.83578082118;3243.108643143509;3278.508223766454;3307.6692330766973;3320.070380293522;3382.953313245476;456948;457609;458410;459018;459801.70099149627;63798.6810065956;64816.35183648103;64839.67580162069;64894;65109;65504.672476637315;65675.67162164158;65737.34262657409;65758.67120664462;65767.67116164484;16453.670926581643;17045.40341088056;17078.23147958345;17093;17166;12972.740545189043;13024.739505209844;13029.739405212033;13046.608601741962;13049.347532623331;15680.843191568167;15959.840401596068;17466.56333591645;17738.467845964642;18313.084345782656;6109.8472538186015;6944.687489063004;7161.713531458683;7171.8565628688175;7208.459365547605;867.4342811225798;1031.6227835839088;1074.9193810464246;1078.4553800330782;1168.6377223060879;29941;30054;30088.09735707932;30814.69185308163;30880.38239235203;1059.147386353977;1077;1077.0952399983894;1085.592902661502;1150.1776229995594;14291.714165716627;15664.216789160497;15747.976381535393;16817.41139060127;16858.747118793228;505167;505379;506114;506187;507151;51967;62415;62474;62849.371506285264;62968;1403.8245219347582;1431.6277767780452;1459.6788706484556;1465.340596731475;1466.8973171877867;31732.206694832814;31931;31956;32110.678893211705;32435;44523.777381112886;54589.18116228339;54945;54951.450485494635;56215;522519;522914;524196;525395;526464;20842.895785520974;24613.13854015101;27018.91924323005;27132.457350852477;27635;34394.65605344014;47510.52489475061;48660;49171.75414122907;49709.757256068886;40984.59015409927;40999.18490686113;41317.58682413137;42194.78902605467;42777.572224277355;39969.60030399659;40893;41180.38229426621;41330;41930.37104443497;39937.60062399455;40605.78182654525;40802.775916722545;40900;40934.79532602318;33407;35027.649723502436;35580.11049723778;36276.81861590675;36466.27067458689;25409.364765881;25835.095771647902;26039.95840166372;26309.925304154334;26628.67298460567 04:10:16 INFO - PROCESS | 1952 | |2;jquery.html;380.62698555415693;390.18840811112887;393.65358484533607;401.4941174120607;413.7476139554871;19397.612047759038;20994.95025248739;21645.809480478605;22029.779702202974;22084.22705205316;20207.292744753937;21070.051847666855;21097.894510527465;21133;21402.46493837655;5114.30956820829;5972.4027597240365;5994.550408719341;6016.669083200422;6017.548683848707;3844.4041173618134;4050.4936882889638;5662.546996240291;5858.414158584129;5951.196588460567;5996.3404025557375;6015.849603759897;6021.608595441304;6028.336882942895;6029.969850150743;6138.84652883677;6620.867582648369;6627.900581491282;6635.369639884203;6637.137172167636;5620.269364982526;5796.769389391688;5804.9419505805245;5841.357450680422;5857.238558987347;12470.43883025266;12476.00191984639;12523.373831308398;12542.310172940484;18109.909450452797;1660.6761681472117;1885.679434496139;2125.6280150973516;2136.327056977047;2319.9651609327357;9978.600855965753;10275.715535558054;10431.5827366906;10439.217058720626;11114.777704445947;1875.6248750249867;1948.5323522354674;1956.2859556262003;1961.0376702116973;2016.3648450737971;95800.52099739526;109127.90872091336;118559.4072029643;121386.99999999911;122682;11574;11581.76836463266;11651.825222621668;11668.183227174017;11849.111316651286;14685.045472044283;14822.407103715947;14826.184559849096;14829.258537073103;14845.925770371296;8579.914200858037;8732.519711415805;8748.8687669685;8808.735737927873;8858.29133669305;8599.484030958163;8634.827303453896;8684.60919258635;8750.64997400097;8804.163604457694;4499.267781903991;4584.701994370355;4594.517575654508;4602.677812553156;4691.812327506861;1771.1586996176713;1799.0156145579951;1829.3505805439145;1899.3447260695038;1904.3715573860593;933.1554942776797;940.3558562384789;971.9805603887883;1004.5479534209635;1062.9627963021258;17090;21283.255086072222;21414.46463838385;21434.678479822815;22003.58944363626;921.8202450522084;927.3925578745932;941.355171707369;951.5860600638745;966.7631430299481;9875.308728389087;11289.830652540219;11396.316221026766;11403.714907127222;11421.37182454973;534711;540696.2285555691;543310;543869;546974;125537;128151;128281.71718282884;128456;130081.34959325333;959.9904000960041;969.8011907559074;989.7030890732751;990.1385794358954;990.153418826907;12522.812157817822;18884.527886802938;18999.145038473027;19183.86980000868;19228.327008554632;51636;53039;53408.93182136413;53499;54828.72585637046;73081.6345918267;75903;76092.61953690196;77680.61159694166;78498.6075069621;5747.453991870766;7376.336129748348;8025.839483210187;8077.919220807952;8420.915790842013;11617.535298587962;12730.68173295638;12983.156094853804;13046.282454464716;13312;10940.12479001662;11007.779844403227;11352.375619340684;11447.141464390037;11493.597724079616;11083.667489975314;11208.943955280172;11256.212065155689;11487.54049837997;11577.247478913843;9644.9035509644;11364.94317528407;11372;11586.073114150679;11589.304641721523;7410.814729631802;7500.987366705425;7520.699172033276;7627.8855817161675;7670.961645191738;10723.731906702084;11298.152638552307;11346.375949322866;11391.544338226378;11482.942585287354 04:10:16 INFO - PROCESS | 1952 | |3;mootools.html;1541.537538738379;1578.9368425262987;1766.5142085926368;1894.138167133954;1906.3518403742733;8410.621522031508;8693.652253909837;8817.426867253624;8846.646134154635;8849.513276769783;5903.793367232137;8013.358931285497;8431.283340916014;8649.740507784762;8686.522241276743;2346.378209774411;2378.583747844129;2380.7738264864897;2383.892724827378;2384.1178763857397;2080.9583808323823;2345.4019225097577;2347.5539647467012;2387.140629373424;2392.3660230038968;1738.8000379956277;2056.897155142237;2133.7332833395826;2367.0531787285054;2367.3253122859987;2295.4376177836452;2345.44881952741;2374.002918774119;2391.473875747333;2392.5334559760854;2352.847064940773;2368.029108065685;2380.595298799208;2382.7855493005713;2392.629142482918;8728.825423491558;8806.163414475615;8810.471371717653;8818.69134580293;8821.206091451735;5241.109011468059;5343.17180836967;5385.138377859563;5392.946070539284;5408.378036525811;3565.3404120237838;3649.927001459956;3668.046307959923;3671.1372827385544;3735.6465426781224;1059.4120263253892;1069.2461814420892;1082.2099867097063;1092.8797832238527;1096.703889949709;11415.657530274106;11587.536498540048;11632.418379081097;11642.359670218219;11679.065674746;4577.908441831211;4662.720236785736;4835.540623640749;4838.298446725275;4845.685030473008;9123.680671176477;9303.116203960613;10019.198464123;10050.195984321237;10073.395596264101;5374.973125134349;5384.461553844662;5393.083175860113;5397.379301380313;5400.351957765093;4729.290606409066;4893.6329775266995;4906.509349065065;4926.113299606033;4927.137750893579;3778.8677396290996;3798.924021519554;3817.7136714746507;3858.344081506151;3878.4764056852528;922.9769255768525;973.8977407372265;1068.529846867376;1135.5628083188033;1156.2311063142915;769.330682306397;863.9481631102154;918.5958178401482;919.9172074513326;946.4699768129732;4718.9292160617615;5500.779968801283;5550.750216240279;5860.648361098348;6028.276606807123;901.1844280925642;1019.9439030853226;1042.9582816687248;1043.3426941027105;1050.4590136079926;3370.4775759757276;3817.389217725206;3837.2325534893134;3965.861194858166;4028;10182.898171018194;10329.896701033043;10765.83851242232;10785.62250321235;10822.296550724177;10597.947010265054;10716.571337146583;10759.515821788038;10765.515551800188;10766;7946.205379462008;8105.189481051965;8290.08809031006;8297;8319.292860106958;5821.476067153976;5923.437273459014;5955.880882382415;5975.40245975399;6052.334243233153;9942.950285248817;10100.090991810772;10109.747256318651;10122.038406351385;10125.493725313852;9800.313978021755;9908.801823963337;10218.846717299395;10223.642172524223;10349.068583827191;7611.505252158592;7641.35048520882;7751.263629955145;7768.223177682186;7776.41676874248;8211.71259005932;8341.541215233221;8419.789505262417;8438.987321521457;8440.873386899078;6266.723619028045;7312.890306645511;7567.394608431202;7629.8855517168395;7632.656530456254;7447.069116360455;7478.887816682863;7532.246775322424;7578.052743407075;7611.923880761122;7066.081409416743;7066.152061752623;7129.679164437509;7149.7497587584185;7154.713811447484;4221.303484924953;4904.828331008398;4939.431965324034;4967.155583550732;5072.644914855925;4385.276429389115;4971.875703107451;4980.626453016111;5027.095122877917;5190.299309593308 04:10:16 INFO - PROCESS | 1952 | |4;prototype.html;303.6674841049051;313.4092236134885;313.836804861472;320.49042023183137;324.5764277617708;4758.310045043467;5344.465553444654;5360.571154307656;5366.248725178469;5366.436524164965;5200.583953283728;5232.686038837673;5323.494268044539;5327.147656374981;5407.270018547495;3806.866759663419;3854.5181852268465;3951.5850835662127;4091.89770255743;4097.180563887235;4016.176683779818;4032.5765794591584;4056.553779084313;4086.7331127350617;4118.526369467505;4013.438118663375;4026.7382620129597;4034.899127521806;4050.96700341414;4066.552679205285;3459.3395170318263;3648.2521083177885;3932.292187406266;3983.860564880244;4058.5129784425762;2820.7884408669433;2959.8076125052016;3016.1403999859936;3481.234128491728;3495.5106285120105;4438.778061096932;5042.697438153686;5075.593952483792;5098.209777484496;5112.284280200774;4259.297215959363;4591.9081618367445;4617.58441740245;4627.167109920213;4647.744374059425;5558.722063896829;5561.332640083176;5562.582806289544;5589.636673616202;5598.356189038272;3732.253549290153;3842.7117966152373;3849.5957924418094;3860.613938606145;3864.632859878307;4993.90012199754;5408.377486753958;5492.697901615409;5647.604667673223;5907.995640741085;3532.523109380252;3578.7673801202836;3853.672437842815;3881.146147847469;3892.474515940312;3321.5848018997626;3535.7524973251625;3852.97896057545;3923.862664806718;3939.290927632996;4235.682323825726;4271.572842715704;4581;4582.381378513858;4589.2657174851865;4671.197281630994;5177.964407118591;5582.985187998516;5593.328800543961;5622.465865742748;1316.3484075382555;1344.5764584156154;1347.47448495087;1351.6215459671307;1353.2489468344966;792.5957761541655;807;830.7424698343533;832.696065935929;835.9801042727862;4990.401151861727;5082.009008243357;5218.347706536683;5253.973730131402;5479.643823151482;5717.828465146053;5725.59920805548;5727.971360143172;5735.483806457355;5784;4163.896567409578;5112.667676601009;5155.871103222375;5223.451537588575;5293;2994.1616347422755;3051.252443151442;3057.4955132403347;3060.8010479318773;3067.9846600766855;5936.7922122725495;5989.011813050885;6006.249218847644;6024.879502409927;6025.276966764016;5497.202905578669;5838.897996721177;5841.240638716939;5873.412658734178;5939.287285525678;5858.56060795442;5893.764249430061;5930.347661757117;5930.644161350333;5983.282006159288;4957.380327459067;5092.414372347227;5405.72971351423;5745.1382292656435;5761.193432919311;4716.481187069488;5662.631928924607;5677.318721753416;5695.316562012585;5789.9710501447225;5034.798608055637;5548.223248745259;6064;6082.847928801815;6120.908186377119;2984.820910745362;3821.1211421373796;3841.8079096045644;3873.7797593759005;3935.134270460494;4541.455025396974;5653.406392328746;5688.943110569007;5744.569157313134;5759.510441612342;5537.889242215214;5570.136628822539;5575.581831362745;5580.469855363734;5583.581231407742;4329.870103896888;4565.497795242454;5212.166053431432;5361.839144825662;5462.289902312674;4646.62826973848;4649.721016739007;4697.060587882369;4767.976160119316;5296.443873393238;4906.607471402208;5438.564914806885;5568.303962004749;5599.272094627672;5652.547796176378;5494.862628434321;5528.723563821713;5531.668099914018;5536.4186760389575;5580.21876937221 04:10:16 INFO - PROCESS | 1952 | |5;yui.html;737.2185483387608;752.65377926154;790.735103740247;858.831988495646;858.9312854971595;15414.075155490678;16494.505164845043;16515;16538.173091345445;16544.75182872258;14727.705445891075;15040.09759414436;15060;15091.773623395631;15098.773518397233;690.261420280299;718.3068339052813;871.1549796697194;906.8685040669098;907.9092090790934;737.2516895351223;891.0510306523531;892.2549671024702;893.4415990006246;895.0870112485261;938.8638647396124;939.7462685075026;940.2947789158131;941.6704153546273;942.2885721280423;734.3721118443707;764.598585742484;909.4361495872536;937.334492510315;951.362587066667;703.388052394415;703.707961196103;703.9472039597;703.9612821294827;705.3722187253348;15071.171085590287;15078.623034424118;15107.32017059235;15131.621709457242;15152;14081.859181408157;14166.433342666282;14175;14192.864570834308;14308.856911430856;13105.803412948813;13193.274369909652;13197.27414992175;13224.404901779442;13613.591592252249;4537.863864084083;4605.263157894721;4675.672702910798;4686.367340409035;4785.904281914342;110297;110774.44612776884;110789;110805.44597276962;110837;10919.924545627016;11057.447127643747;11067.778644427066;11113.999740023275;11268.380239086766;22008;22269.888650556644;22288.885555722474;22304.55390892173;22311.888440558017;7189.712411503481;7868.881966770388;8005.319547838499;8143.348532117417;8188.14024527416;4452.888677783017;4473.4408198975125;4497.325401189848;4498.613851494832;4640.79116439761;3437.2094418283937;3946.309395855714;4128.669706423479;4129.463169787909;4140;721.6896734404152;738.2949283434307;741.6032422653855;748.2666986353296;750.6396929473883;560.9242752228479;675.7700984208693;680.6664734280167;684.736376495053;702.3117345001926;9688;10117.848232276521;10198.359641595645;10434.37393756377;10561.039610146196;637.1173183243504;655.2661019658013;682.3449488491005;684.1174884399169;687.7077242172057;4496.975207189547;4616.722996620214;4631.212693842055;4668.323093151474;4674.345591617176;116835.41582292205;120142;136314;145943;146282;125077;125238.12142817865;126040;126725.36637316938;128211;65825.67087164629;65937.34062659409;89130;104510;106657;9633.133018028411;9706.611735530782;9796.262330838306;9887.258455615714;9939.204863611038;57673.55816104463;58840.411595885205;60044.099338509084;60719;61034;94274.52862735643;97945.51027244818;98622;98622;100680.91828980569;13361.799573006218;13377.464901403835;13437.798433023709;13438.328083595976;13535.187888726708;11898;14892;15011.399544018117;15048.172350520827;15094.924525377302;14041.859581404055;14330.928345358207;14353.210573418562;14369.425222990963;14393.280335983369;13985.160890346611;14098.647533811327;14230.430782768573;14343.426262949783;14464.710705786036;13844.307784610528;14028.22844743508;14087.929560352133;14381.424743010162;14751;11229.7754044917;11529.884701152881;11591.130665199975;11591.826122608;11758.94120529426;11137.888621113685;11232.213745037769;11474.541018359507;11780.941095294469;11878.287302761863 04:10:16 INFO - PROCESS | 1952 | __end_tp_report 04:10:16 INFO - PROCESS | 1952 | __start_cc_report 04:10:16 INFO - PROCESS | 1952 | _x_x_mozilla_cycle_collect,1130 04:10:16 INFO - PROCESS | 1952 | __end_cc_report 04:10:16 INFO - PROCESS | 1952 | __startTimestamp1468840216243__endTimestamp 04:10:16 INFO - PROCESS | 1952 | 04:10:16 INFO - PROCESS | 1952 | ------- Summary: start ------- 04:10:16 INFO - PROCESS | 1952 | Number of tests: 6 04:10:16 INFO - PROCESS | 1952 | 04:10:16 INFO - PROCESS | 1952 | [#0] dojo.html Cycles:165 Average:5701.28 Median:5916.35 stddev:642.19 (10.9%) stddev-sans-first:617.01 04:10:16 INFO - PROCESS | 1952 | Values: 3346.4 3358.9 3380.9 3416.4 3449.1 5598.9 5619.0 5656.9 5698.1 5733.8 4863.5 5643.5 5708.0 5759.1 5821.6 5113.5 5124.3 5194.7 5205.4 5291.9 5283.2 5289.1 5308.7 5326.3 5354.6 5062.2 5151.4 5153.1 5211.7 5233.3 5139.4 5168.5 5192.8 5202.0 5205.3 5088.3 5141.0 5166.1 5228.3 5264.7 5680.0 5690.4 5704.7 5749.5 5769.0 6042.4 6115.1 6233.2 6239.9 6257.9 6077.8 6133.9 6168.0 6235.0 6256.3 5764.0 5781.9 5783.2 5787.2 5795.6 5156.9 6295.8 6296.6 6330.9 6341.9 6077.5 6096.7 6110.7 6164.2 6187.3 6263.2 6280.7 6285.6 6292.6 6296.7 6017.0 6104.3 6225.3 6227.3 6244.0 6037.3 6073.2 6106.0 6149.0 6166.9 4859.2 4864.4 4865.3 4870.8 4915.0 4369.3 4397.2 4398.8 4419.8 4430.8 5675.8 5695.1 5736.7 5761.2 5848.1 6221.3 6266.5 6289.7 6297.2 6308.3 5613.4 5644.7 5661.4 5672.2 5674.4 5311.3 5341.8 5348.4 5369.9 5407.7 6287.9 6307.4 6311.3 6329.3 6334.2 5969.4 6093.7 6153.5 6209.5 6232.2 6294.5 6302.6 6308.9 6310.0 6312.3 6076.0 6139.3 6163.9 6173.4 6196.6 6033.7 6098.2 6135.6 6251.8 6295.2 5006.8 6067.8 6069.1 6099.5 6129.6 4568.7 6061.2 6061.5 6071.3 6080.5 5649.9 5759.3 5913.9 5924.6 5933.7 6106.5 6116.3 6124.0 6126.0 6133.9 5913.9 5916.3 5917.2 5928.1 5928.1 04:10:16 INFO - PROCESS | 1952 | 04:10:16 INFO - PROCESS | 1952 | [#1] ext.html Cycles:180 Average:65050.92 Median:20075.71 stddev:131948.01 (657.3%) stddev-sans-first:132229.11 04:10:16 INFO - PROCESS | 1952 | Values: 484.4 522.4 535.1 554.7 556.2 30538.7 32093.8 32933.5 42728.8 42865.8 44380.0 44412.3 44441.9 44539.8 44728.6 9776.5 9958.0 9980.2 9989.9 10001.9 8860.5 9040.0 9040.5 9202.2 9228.0 10475.4 12084.2 12099.9 12369.9 12395.8 12110.3 12200.3 12202.3 12205.7 12211.6 8840.6 11705.9 11999.0 12074.3 12165.5 42138.2 42529.6 42550.8 42613.4 42710.1 3450.7 3464.3 3607.2 3685.2 3813.3 31327.4 31880.8 32465.0 32583.5 32843.8 3243.1 3278.5 3307.7 3320.1 3383.0 456948.0 457609.0 458410.0 459018.0 459801.7 63798.7 64816.4 64839.7 64894.0 65109.0 65504.7 65675.7 65737.3 65758.7 65767.7 16453.7 17045.4 17078.2 17093.0 17166.0 12972.7 13024.7 13029.7 13046.6 13049.3 15680.8 15959.8 17466.6 17738.5 18313.1 6109.8 6944.7 7161.7 7171.9 7208.5 867.4 1031.6 1074.9 1078.5 1168.6 29941.0 30054.0 30088.1 30814.7 30880.4 1059.1 1077.0 1077.1 1085.6 1150.2 14291.7 15664.2 15748.0 16817.4 16858.7 505167.0 505379.0 506114.0 506187.0 507151.0 51967.0 62415.0 62474.0 62849.4 62968.0 1403.8 1431.6 1459.7 1465.3 1466.9 31732.2 31931.0 31956.0 32110.7 32435.0 44523.8 54589.2 54945.0 54951.5 56215.0 522519.0 522914.0 524196.0 525395.0 526464.0 20842.9 24613.1 27018.9 27132.5 27635.0 34394.7 47510.5 48660.0 49171.8 49709.8 40984.6 40999.2 41317.6 42194.8 42777.6 39969.6 40893.0 41180.4 41330.0 41930.4 39937.6 40605.8 40802.8 40900.0 40934.8 33407.0 35027.6 35580.1 36276.8 36466.3 25409.4 25835.1 26040.0 26309.9 26628.7 04:10:16 INFO - PROCESS | 1952 | 04:10:16 INFO - PROCESS | 1952 | [#2] jquery.html Cycles:180 Average:33011.82 Median:22057.00 stddev:90940.85 (412.3%) stddev-sans-first:91162.95 04:10:16 INFO - PROCESS | 1952 | Values: 380.6 390.2 393.7 401.5 413.7 19397.6 20995.0 21645.8 22029.8 22084.2 20207.3 21070.1 21097.9 21133.0 21402.5 5114.3 5972.4 5994.6 6016.7 6017.5 3844.4 4050.5 5662.5 5858.4 5951.2 5996.3 6015.8 6021.6 6028.3 6030.0 6138.8 6620.9 6627.9 6635.4 6637.1 5620.3 5796.8 5804.9 5841.4 5857.2 12470.4 12476.0 12523.4 12542.3 18109.9 1660.7 1885.7 2125.6 2136.3 2320.0 9978.6 10275.7 10431.6 10439.2 11114.8 1875.6 1948.5 1956.3 1961.0 2016.4 95800.5 109127.9 118559.4 121387.0 122682.0 11574.0 11581.8 11651.8 11668.2 11849.1 14685.0 14822.4 14826.2 14829.3 14845.9 8579.9 8732.5 8748.9 8808.7 8858.3 8599.5 8634.8 8684.6 8750.6 8804.2 4499.3 4584.7 4594.5 4602.7 4691.8 1771.2 1799.0 1829.4 1899.3 1904.4 933.2 940.4 972.0 1004.5 1063.0 17090.0 21283.3 21414.5 21434.7 22003.6 921.8 927.4 941.4 951.6 966.8 9875.3 11289.8 11396.3 11403.7 11421.4 534711.0 540696.2 543310.0 543869.0 546974.0 125537.0 128151.0 128281.7 128456.0 130081.3 960.0 969.8 989.7 990.1 990.2 12522.8 18884.5 18999.1 19183.9 19228.3 51636.0 53039.0 53408.9 53499.0 54828.7 73081.6 75903.0 76092.6 77680.6 78498.6 5747.5 7376.3 8025.8 8077.9 8420.9 11617.5 12730.7 12983.2 13046.3 13312.0 10940.1 11007.8 11352.4 11447.1 11493.6 11083.7 11208.9 11256.2 11487.5 11577.2 9644.9 11364.9 11372.0 11586.1 11589.3 7410.8 7501.0 7520.7 7627.9 7671.0 10723.7 11298.2 11346.4 11391.5 11482.9 04:10:16 INFO - PROCESS | 1952 | 04:10:16 INFO - PROCESS | 1952 | [#3] mootools.html Cycles:180 Average:5684.07 Median:4869.66 stddev:3195.15 (65.6%) stddev-sans-first:3188.95 04:10:16 INFO - PROCESS | 1952 | Values: 1541.5 1578.9 1766.5 1894.1 1906.4 8410.6 8693.7 8817.4 8846.6 8849.5 5903.8 8013.4 8431.3 8649.7 8686.5 2346.4 2378.6 2380.8 2383.9 2384.1 2081.0 2345.4 2347.6 2387.1 2392.4 1738.8 2056.9 2133.7 2367.1 2367.3 2295.4 2345.4 2374.0 2391.5 2392.5 2352.8 2368.0 2380.6 2382.8 2392.6 8728.8 8806.2 8810.5 8818.7 8821.2 5241.1 5343.2 5385.1 5392.9 5408.4 3565.3 3649.9 3668.0 3671.1 3735.6 1059.4 1069.2 1082.2 1092.9 1096.7 11415.7 11587.5 11632.4 11642.4 11679.1 4577.9 4662.7 4835.5 4838.3 4845.7 9123.7 9303.1 10019.2 10050.2 10073.4 5375.0 5384.5 5393.1 5397.4 5400.4 4729.3 4893.6 4906.5 4926.1 4927.1 3778.9 3798.9 3817.7 3858.3 3878.5 923.0 973.9 1068.5 1135.6 1156.2 769.3 863.9 918.6 919.9 946.5 4718.9 5500.8 5550.8 5860.6 6028.3 901.2 1019.9 1043.0 1043.3 1050.5 3370.5 3817.4 3837.2 3965.9 4028.0 10182.9 10329.9 10765.8 10785.6 10822.3 10597.9 10716.6 10759.5 10765.5 10766.0 7946.2 8105.2 8290.1 8297.0 8319.3 5821.5 5923.4 5955.9 5975.4 6052.3 9943.0 10100.1 10109.7 10122.0 10125.5 9800.3 9908.8 10218.8 10223.6 10349.1 7611.5 7641.4 7751.3 7768.2 7776.4 8211.7 8341.5 8419.8 8439.0 8440.9 6266.7 7312.9 7567.4 7629.9 7632.7 7447.1 7478.9 7532.2 7578.1 7611.9 7066.1 7066.2 7129.7 7149.7 7154.7 4221.3 4904.8 4939.4 4967.2 5072.6 4385.3 4971.9 4980.6 5027.1 5190.3 04:10:16 INFO - PROCESS | 1952 | 04:10:16 INFO - PROCESS | 1952 | [#4] prototype.html Cycles:180 Average:4515.43 Median:5095.31 stddev:1404.45 (27.6%) stddev-sans-first:1372.35 04:10:16 INFO - PROCESS | 1952 | Values: 303.7 313.4 313.8 320.5 324.6 4758.3 5344.5 5360.6 5366.2 5366.4 5200.6 5232.7 5323.5 5327.1 5407.3 3806.9 3854.5 3951.6 4091.9 4097.2 4016.2 4032.6 4056.6 4086.7 4118.5 4013.4 4026.7 4034.9 4051.0 4066.6 3459.3 3648.3 3932.3 3983.9 4058.5 2820.8 2959.8 3016.1 3481.2 3495.5 4438.8 5042.7 5075.6 5098.2 5112.3 4259.3 4591.9 4617.6 4627.2 4647.7 5558.7 5561.3 5562.6 5589.6 5598.4 3732.3 3842.7 3849.6 3860.6 3864.6 4993.9 5408.4 5492.7 5647.6 5908.0 3532.5 3578.8 3853.7 3881.1 3892.5 3321.6 3535.8 3853.0 3923.9 3939.3 4235.7 4271.6 4581.0 4582.4 4589.3 4671.2 5178.0 5583.0 5593.3 5622.5 1316.3 1344.6 1347.5 1351.6 1353.2 792.6 807.0 830.7 832.7 836.0 4990.4 5082.0 5218.3 5254.0 5479.6 5717.8 5725.6 5728.0 5735.5 5784.0 4163.9 5112.7 5155.9 5223.5 5293.0 2994.2 3051.3 3057.5 3060.8 3068.0 5936.8 5989.0 6006.2 6024.9 6025.3 5497.2 5838.9 5841.2 5873.4 5939.3 5858.6 5893.8 5930.3 5930.6 5983.3 4957.4 5092.4 5405.7 5745.1 5761.2 4716.5 5662.6 5677.3 5695.3 5790.0 5034.8 5548.2 6064.0 6082.8 6120.9 2984.8 3821.1 3841.8 3873.8 3935.1 4541.5 5653.4 5688.9 5744.6 5759.5 5537.9 5570.1 5575.6 5580.5 5583.6 4329.9 4565.5 5212.2 5361.8 5462.3 4646.6 4649.7 4697.1 4768.0 5296.4 4906.6 5438.6 5568.3 5599.3 5652.5 5494.9 5528.7 5531.7 5536.4 5580.2 04:10:16 INFO - PROCESS | 1952 | 04:10:16 INFO - PROCESS | 1952 | [#5] yui.html Cycles:180 Average:24100.68 Median:22308.22 stddev:37163.35 (166.6%) stddev-sans-first:37226.20 04:10:16 INFO - PROCESS | 1952 | Values: 737.2 752.7 790.7 858.8 858.9 15414.1 16494.5 16515.0 16538.2 16544.8 14727.7 15040.1 15060.0 15091.8 15098.8 690.3 718.3 871.2 906.9 907.9 737.3 891.1 892.3 893.4 895.1 938.9 939.7 940.3 941.7 942.3 734.4 764.6 909.4 937.3 951.4 703.4 703.7 703.9 704.0 705.4 15071.2 15078.6 15107.3 15131.6 15152.0 14081.9 14166.4 14175.0 14192.9 14308.9 13105.8 13193.3 13197.3 13224.4 13613.6 4537.9 4605.3 4675.7 4686.4 4785.9 110297.0 110774.4 110789.0 110805.4 110837.0 10919.9 11057.4 11067.8 11114.0 11268.4 22008.0 22269.9 22288.9 22304.6 22311.9 7189.7 7868.9 8005.3 8143.3 8188.1 4452.9 4473.4 4497.3 4498.6 4640.8 3437.2 3946.3 4128.7 4129.5 4140.0 721.7 738.3 741.6 748.3 750.6 560.9 675.8 680.7 684.7 702.3 9688.0 10117.8 10198.4 10434.4 10561.0 637.1 655.3 682.3 684.1 687.7 4497.0 4616.7 4631.2 4668.3 4674.3 116835.4 120142.0 136314.0 145943.0 146282.0 125077.0 125238.1 126040.0 126725.4 128211.0 65825.7 65937.3 89130.0 104510.0 106657.0 9633.1 9706.6 9796.3 9887.3 9939.2 57673.6 58840.4 60044.1 60719.0 61034.0 94274.5 97945.5 98622.0 98622.0 100680.9 13361.8 13377.5 13437.8 13438.3 13535.2 11898.0 14892.0 15011.4 15048.2 15094.9 14041.9 14330.9 14353.2 14369.4 14393.3 13985.2 14098.6 14230.4 14343.4 14464.7 13844.3 14028.2 14087.9 14381.4 14751.0 11229.8 11529.9 11591.1 11591.8 11758.9 11137.9 11232.2 11474.5 11780.9 11878.3 04:10:16 INFO - PROCESS | 1952 | -------- Summary: end -------- 04:10:16 INFO - PROCESS | 1952 | 04:10:16 INFO - TEST-INFO | 1952: exit 0 04:10:16 INFO - TEST-OK | dromaeo_css | took 1116356ms 04:10:16 INFO - TEST-START | kraken 04:10:16 INFO - operating with platform_type : mac_ 04:10:16 INFO - Initialising browser for kraken test... 04:10:16 INFO - TEST-INFO | started process 1959 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/tmpD8kV7W/profile http://localhost:52623/getInfo.html) 04:10:19 INFO - PROCESS | 1959 | JavaScript error: , line 0: uncaught exception: undefined 04:10:19 INFO - PROCESS | 1959 | __metrics Screen width/height:1600/1200 04:10:19 INFO - PROCESS | 1959 | colorDepth:24 04:10:19 INFO - PROCESS | 1959 | Browser inner width/height: 1024/689 04:10:19 INFO - PROCESS | 1959 | __metrics 04:10:19 INFO - PROCESS | 1959 | 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" 04:10:19 INFO - PROCESS | 1959 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:10:20 INFO - TEST-INFO | 1959: exit 0 04:10:20 INFO - Browser initialized. 04:10:20 INFO - Running cycle 1/1 for kraken test... 04:10:20 INFO - TEST-INFO | started process 1960 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/tmpD8kV7W/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:10:32 INFO - PROCESS | 1960 | RSS: Main: 245166080 04:10:32 INFO - PROCESS | 1960 | 04:10:34 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:52623/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52623/tests/kraken/driver.html?testName=audio-beat-detection) 04:10:35 INFO - PROCESS | 1960 | RSS: Main: 295206912 04:10:35 INFO - PROCESS | 1960 | 04:10:38 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:52623/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52623/tests/kraken/driver.html?testName=audio-dft) 04:10:38 INFO - PROCESS | 1960 | RSS: Main: 301363200 04:10:38 INFO - PROCESS | 1960 | 04:10:42 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:52623/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52623/tests/kraken/driver.html?testName=audio-fft) 04:10:43 INFO - PROCESS | 1960 | RSS: Main: 390418432 04:10:43 INFO - PROCESS | 1960 | 04:10:45 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:52623/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52623/tests/kraken/driver.html?testName=audio-oscillator) 04:10:45 INFO - PROCESS | 1960 | RSS: Main: 291254272 04:10:45 INFO - PROCESS | 1960 | 04:10:47 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:52623/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52623/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:10:48 INFO - PROCESS | 1960 | RSS: Main: 295534592 04:10:48 INFO - PROCESS | 1960 | 04:10:51 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:52623/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52623/tests/kraken/driver.html?testName=imaging-darkroom) 04:10:51 INFO - PROCESS | 1960 | RSS: Main: 330838016 04:10:51 INFO - PROCESS | 1960 | 04:10:54 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:52623/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52623/tests/kraken/driver.html?testName=imaging-desaturate) 04:10:55 INFO - PROCESS | 1960 | RSS: Main: 334979072 04:10:55 INFO - PROCESS | 1960 | 04:10:58 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:52623/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52623/tests/kraken/driver.html?testName=json-parse-financial) 04:10:58 INFO - PROCESS | 1960 | RSS: Main: 333553664 04:10:58 INFO - PROCESS | 1960 | 04:11:00 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:52623/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52623/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:11:00 INFO - PROCESS | 1960 | RSS: Main: 312037376 04:11:00 INFO - PROCESS | 1960 | 04:11:02 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:52623/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52623/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:11:03 INFO - PROCESS | 1960 | RSS: Main: 319238144 04:11:03 INFO - PROCESS | 1960 | 04:11:05 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:52623/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52623/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:11:06 INFO - PROCESS | 1960 | RSS: Main: 316239872 04:11:06 INFO - PROCESS | 1960 | 04:11:09 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:52623/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52623/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:11:09 INFO - PROCESS | 1960 | RSS: Main: 325115904 04:11:09 INFO - PROCESS | 1960 | 04:11:12 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:52623/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52623/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:11:13 INFO - PROCESS | 1960 | RSS: Main: 326696960 04:11:13 INFO - PROCESS | 1960 | 04:11:15 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:52623/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52623/tests/kraken/driver.html?testName=ai-astar) 04:11:15 INFO - PROCESS | 1960 | RSS: Main: 370098176 04:11:15 INFO - PROCESS | 1960 | 04:11:15 INFO - PROCESS | 1960 | __start_tp_report 04:11:15 INFO - PROCESS | 1960 | _x_x_mozilla_page_load 04:11:15 INFO - PROCESS | 1960 | _x_x_mozilla_page_load_details 04:11:15 INFO - PROCESS | 1960 | |i|pagename|runs| 04:11:15 INFO - PROCESS | 1960 | |0;ai-astar;102;113;112;114;113;112;115;113;112;114 04:11:15 INFO - PROCESS | 1960 | |1;audio-beat-detection;140;110;107;161;106;134;134;106;160;107 04:11:15 INFO - PROCESS | 1960 | |2;audio-dft;185;183;184;185;184;182;195;198;184;182 04:11:15 INFO - PROCESS | 1960 | |3;audio-fft;79;57;56;107;58;58;60;63;97;57 04:11:15 INFO - PROCESS | 1960 | |4;audio-oscillator;83;70;92;70;73;67;98;69;67;98 04:11:15 INFO - PROCESS | 1960 | |5;imaging-gaussian-blur;91;88;91;87;91;88;90;88;91;86 04:11:15 INFO - PROCESS | 1960 | |6;imaging-darkroom;95;96;96;96;95;96;95;96;94;95 04:11:15 INFO - PROCESS | 1960 | |7;imaging-desaturate;89;89;87;88;102;142;141;86;89;83 04:11:15 INFO - PROCESS | 1960 | |8;json-parse-financial;55;55;55;54;53;53;54;53;52;54 04:11:15 INFO - PROCESS | 1960 | |9;json-stringify-tinderbox;67;66;78;80;72;72;71;71;70;72 04:11:15 INFO - PROCESS | 1960 | |10;stanford-crypto-aes;83;82;83;83;84;82;81;82;85;79 04:11:15 INFO - PROCESS | 1960 | |11;stanford-crypto-ccm;136;146;140;142;146;148;137;135;175;135 04:11:15 INFO - PROCESS | 1960 | |12;stanford-crypto-pbkdf2;198;174;172;173;172;174;173;171;170;171 04:11:15 INFO - PROCESS | 1960 | |13;stanford-crypto-sha256-iterative;69;69;68;70;68;69;69;84;85;84 04:11:15 INFO - PROCESS | 1960 | __end_tp_report 04:11:15 INFO - PROCESS | 1960 | __start_cc_report 04:11:15 INFO - PROCESS | 1960 | _x_x_mozilla_cycle_collect,2051 04:11:15 INFO - PROCESS | 1960 | __end_cc_report 04:11:15 INFO - PROCESS | 1960 | __startTimestamp1468840275504__endTimestamp 04:11:15 INFO - PROCESS | 1960 | 04:11:15 INFO - PROCESS | 1960 | ------- Summary: start ------- 04:11:15 INFO - PROCESS | 1960 | Number of tests: 14 04:11:15 INFO - PROCESS | 1960 | 04:11:15 INFO - PROCESS | 1960 | [#0] ai-astar Cycles:10 Average:112.00 Median:113.00 stddev:3.65 (3.2%) stddev-sans-first:1.05 04:11:15 INFO - PROCESS | 1960 | Values: 102.0 113.0 112.0 114.0 113.0 112.0 115.0 113.0 112.0 114.0 04:11:15 INFO - PROCESS | 1960 | 04:11:15 INFO - PROCESS | 1960 | [#1] audio-beat-detection Cycles:10 Average:126.50 Median:134.00 stddev:22.31 (16.7%) stddev-sans-first:23.12 04:11:15 INFO - PROCESS | 1960 | Values: 140.0 110.0 107.0 161.0 106.0 134.0 134.0 106.0 160.0 107.0 04:11:15 INFO - PROCESS | 1960 | 04:11:15 INFO - PROCESS | 1960 | [#2] audio-dft Cycles:10 Average:186.20 Median:184.50 stddev:5.57 (3.0%) stddev-sans-first:5.89 04:11:15 INFO - PROCESS | 1960 | Values: 185.0 183.0 184.0 185.0 184.0 182.0 195.0 198.0 184.0 182.0 04:11:15 INFO - PROCESS | 1960 | 04:11:15 INFO - PROCESS | 1960 | [#3] audio-fft Cycles:10 Average:69.20 Median:59.00 stddev:18.69 (31.7%) stddev-sans-first:19.48 04:11:15 INFO - PROCESS | 1960 | Values: 79.0 57.0 56.0 107.0 58.0 58.0 60.0 63.0 97.0 57.0 04:11:15 INFO - PROCESS | 1960 | 04:11:15 INFO - PROCESS | 1960 | [#4] audio-oscillator Cycles:10 Average:78.70 Median:78.00 stddev:12.88 (16.5%) stddev-sans-first:13.56 04:11:15 INFO - PROCESS | 1960 | Values: 83.0 70.0 92.0 70.0 73.0 67.0 98.0 69.0 67.0 98.0 04:11:15 INFO - PROCESS | 1960 | 04:11:15 INFO - PROCESS | 1960 | [#5] imaging-gaussian-blur Cycles:10 Average:89.10 Median:90.50 stddev:1.91 (2.1%) stddev-sans-first:1.90 04:11:15 INFO - PROCESS | 1960 | Values: 91.0 88.0 91.0 87.0 91.0 88.0 90.0 88.0 91.0 86.0 04:11:15 INFO - PROCESS | 1960 | 04:11:15 INFO - PROCESS | 1960 | [#6] imaging-darkroom Cycles:10 Average:95.40 Median:96.00 stddev:0.70 (0.7%) stddev-sans-first:0.73 04:11:15 INFO - PROCESS | 1960 | Values: 95.0 96.0 96.0 96.0 95.0 96.0 95.0 96.0 94.0 95.0 04:11:15 INFO - PROCESS | 1960 | 04:11:15 INFO - PROCESS | 1960 | [#7] imaging-desaturate Cycles:10 Average:99.60 Median:87.50 stddev:22.63 (25.9%) stddev-sans-first:23.67 04:11:15 INFO - PROCESS | 1960 | Values: 89.0 89.0 87.0 88.0 102.0 142.0 141.0 86.0 89.0 83.0 04:11:15 INFO - PROCESS | 1960 | 04:11:15 INFO - PROCESS | 1960 | [#8] json-parse-financial Cycles:10 Average:53.80 Median:54.00 stddev:1.03 (1.9%) stddev-sans-first:1.00 04:11:15 INFO - PROCESS | 1960 | Values: 55.0 55.0 55.0 54.0 53.0 53.0 54.0 53.0 52.0 54.0 04:11:15 INFO - PROCESS | 1960 | 04:11:15 INFO - PROCESS | 1960 | [#9] json-stringify-tinderbox Cycles:10 Average:71.90 Median:72.00 stddev:4.31 (6.0%) stddev-sans-first:4.19 04:11:15 INFO - PROCESS | 1960 | Values: 67.0 66.0 78.0 80.0 72.0 72.0 71.0 71.0 70.0 72.0 04:11:15 INFO - PROCESS | 1960 | 04:11:15 INFO - PROCESS | 1960 | [#10] stanford-crypto-aes Cycles:10 Average:82.40 Median:83.00 stddev:1.65 (2.0%) stddev-sans-first:1.73 04:11:15 INFO - PROCESS | 1960 | Values: 83.0 82.0 83.0 83.0 84.0 82.0 81.0 82.0 85.0 79.0 04:11:15 INFO - PROCESS | 1960 | 04:11:15 INFO - PROCESS | 1960 | [#11] stanford-crypto-ccm Cycles:10 Average:144.00 Median:144.00 stddev:11.93 (8.3%) stddev-sans-first:12.29 04:11:15 INFO - PROCESS | 1960 | Values: 136.0 146.0 140.0 142.0 146.0 148.0 137.0 135.0 175.0 135.0 04:11:15 INFO - PROCESS | 1960 | 04:11:15 INFO - PROCESS | 1960 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.80 Median:173.00 stddev:8.26 (4.8%) stddev-sans-first:1.39 04:11:15 INFO - PROCESS | 1960 | Values: 198.0 174.0 172.0 173.0 172.0 174.0 173.0 171.0 170.0 171.0 04:11:15 INFO - PROCESS | 1960 | 04:11:15 INFO - PROCESS | 1960 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.50 Median:69.50 stddev:7.50 (10.8%) stddev-sans-first:7.78 04:11:15 INFO - PROCESS | 1960 | Values: 69.0 69.0 68.0 70.0 68.0 69.0 69.0 84.0 85.0 84.0 04:11:15 INFO - PROCESS | 1960 | -------- Summary: end -------- 04:11:15 INFO - PROCESS | 1960 | 04:11:16 INFO - TEST-INFO | 1960: exit 0 04:11:16 INFO - TEST-OK | kraken | took 59508ms 04:11:16 INFO - SUITE-END | took 1175s 04:11:16 INFO - Completed test suite (00:19:36) 04:11:16 INFO - javascript benchmark 04:11:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3346.431106711859, 3358.9160270993207, 3380.915477113072, 3416.436288012478, 3449.103233159379, 5598.888022239553, 5618.971905140474, 5656.943430565693, 5698.116791897258, 5733.770649174034, 4863.489333619972, 5643.520300774439, 5707.972564938309, 5759.10733836255, 5821.56338274629, 5113.463086375933, 5124.308218390525, 5194.688318700871, 5205.427402985669, 5291.947080529203, 5283.154695248761, 5289.100852855024, 5308.70802105884, 5326.334208223972, 5354.598405119612, 5062.215356619693, 5151.407588127376, 5153.149730294497, 5211.739413029352, 5233.293505376782, 5139.408967968694, 5168.457311982225, 5192.844214673566, 5202.0, 5205.349331333583, 5088.287639730441, 5141.0, 5166.07010738067, 5228.346456692913, 5264.710440925747, 5680.034394153004, 5690.430956904318, 5704.657720536781, 5749.511291540224, 5768.961586914354, 6042.395760423967, 6115.052166914135, 6233.189685340877, 6239.906401403982, 6257.906131408033, 6077.816817056593, 6133.938660613381, 6167.982282923313, 6234.968825155891, 6256.311805701369, 5764.0, 5781.942180578225, 5783.190353350492, 5787.160861675034, 5795.565332600072, 5156.948430515722, 6295.842603934938, 6296.5592408531875, 6330.905036424458, 6341.936580634135, 6077.453029227391, 6096.677875983316, 6110.686202466491, 6164.198654174928, 6187.288461826857, 6263.154474145932, 6280.717367718464, 6285.622862628257, 6292.559520833498, 6296.716647750863, 6017.0, 6104.298005729309, 6225.346338634468, 6227.346128656426, 6244.0321750128805, 6037.275526936795, 6073.240844894388, 6106.0, 6148.9692551537855, 6166.907496387557, 4859.173940430064, 4864.440589332201, 4865.318855360252, 4870.780814863339, 4914.975425122923, 4369.2572262714775, 4397.1865204937185, 4398.8020539075815, 4419.823207071746, 4430.82276708928, 5675.77296908119, 5695.060315048054, 5736.68448235351, 5761.193432919395, 5848.093545500455, 6221.2845522764555, 6266.467350275187, 6289.654069026156, 6297.244330680256, 6308.337624549448, 5613.382527921925, 5644.74598643062, 5661.377248502661, 5672.234248376499, 5674.375818659944, 5311.336082989626, 5341.813036543703, 5348.3849357323625, 5369.892602147936, 5407.675539467566, 6287.937120628827, 6307.400796924285, 6311.274203466476, 6329.272133704683, 6334.176557047646, 5969.403059693996, 6093.695315234132, 6153.507719382349, 6209.503239740719, 6232.158658581034, 6294.464970477561, 6302.621842689453, 6308.936910630835, 6310.021946598285, 6312.3056463788025, 6075.969620151871, 6139.293981192041, 6163.876722465615, 6173.41352571505, 6196.566240363131, 6033.668148251888, 6098.220442830473, 6135.601185922901, 6251.812445626639, 6295.213098362705, 5006.849794506171, 6067.787627433018, 6069.089636554376, 6099.542534309856, 6129.570930035033, 4568.70303430276, 6061.15143879866, 6061.454469097626, 6071.301800293023, 6080.513558915188, 5649.943500565106, 5759.280089988752, 5913.940860591511, 5924.6149000314845, 5933.703314834154, 6106.49633650219, 6116.27490396663, 6123.984276525883, 6125.9500109712835, 6133.877322453615, 5913.91129133072, 5916.3492015877355, 5917.201177840936, 5928.081147422156, 5928.140419639214], "value": 5661.729462815303, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [484.3678998906428, 522.4122861780497, 535.0529562674069, 554.7475898466197, 556.2323992889811, 30538.694613053864, 32093.839530802343, 32933.50599741003, 42728.78635606825, 42865.7856710716, 44380.0, 44412.3338149928, 44441.8889527762, 44539.77730111353, 44728.55271447285, 9776.462294573814, 9958.0, 9980.151687106569, 9989.900100999006, 10001.899981000206, 8860.468371897674, 9040.0, 9040.547972601344, 9202.171804537591, 9228.0, 10475.371477711324, 12084.154109212359, 12099.939500302486, 12369.938150309235, 12395.81406278902, 12110.273383596968, 12200.328981905992, 12202.328871912041, 12205.694857628541, 12211.572594959134, 8840.646374145026, 11705.882941170565, 11999.0, 12074.335911524862, 12165.452554635063, 42138.1572368554, 42529.57470425318, 42550.78724606388, 42613.36079958803, 42710.14579708388, 3450.706689931359, 3464.2898205867737, 3607.2244467439627, 3685.152414944551, 3813.3326667833294, 31327.37345253105, 31880.840595797104, 32465.0, 32583.51124733131, 32843.83578082118, 3243.108643143509, 3278.508223766454, 3307.6692330766973, 3320.070380293522, 3382.953313245476, 456948.0, 457609.0, 458410.0, 459018.0, 459801.70099149627, 63798.6810065956, 64816.35183648103, 64839.67580162069, 64894.0, 65109.0, 65504.672476637315, 65675.67162164158, 65737.34262657409, 65758.67120664462, 65767.67116164484, 16453.670926581643, 17045.40341088056, 17078.23147958345, 17093.0, 17166.0, 12972.740545189043, 13024.739505209844, 13029.739405212033, 13046.608601741962, 13049.347532623331, 15680.843191568167, 15959.840401596068, 17466.56333591645, 17738.467845964642, 18313.084345782656, 6109.8472538186015, 6944.687489063004, 7161.713531458683, 7171.8565628688175, 7208.459365547605, 867.4342811225798, 1031.6227835839088, 1074.9193810464246, 1078.4553800330782, 1168.6377223060879, 29941.0, 30054.0, 30088.09735707932, 30814.69185308163, 30880.38239235203, 1059.147386353977, 1077.0, 1077.0952399983894, 1085.592902661502, 1150.1776229995594, 14291.714165716627, 15664.216789160497, 15747.976381535393, 16817.41139060127, 16858.747118793228, 505167.0, 505379.0, 506114.0, 506187.0, 507151.0, 51967.0, 62415.0, 62474.0, 62849.371506285264, 62968.0, 1403.8245219347582, 1431.6277767780452, 1459.6788706484556, 1465.340596731475, 1466.8973171877867, 31732.206694832814, 31931.0, 31956.0, 32110.678893211705, 32435.0, 44523.777381112886, 54589.18116228339, 54945.0, 54951.450485494635, 56215.0, 522519.0, 522914.0, 524196.0, 525395.0, 526464.0, 20842.895785520974, 24613.13854015101, 27018.91924323005, 27132.457350852477, 27635.0, 34394.65605344014, 47510.52489475061, 48660.0, 49171.75414122907, 49709.757256068886, 40984.59015409927, 40999.18490686113, 41317.58682413137, 42194.78902605467, 42777.572224277355, 39969.60030399659, 40893.0, 41180.38229426621, 41330.0, 41930.37104443497, 39937.60062399455, 40605.78182654525, 40802.775916722545, 40900.0, 40934.79532602318, 33407.0, 35027.649723502436, 35580.11049723778, 36276.81861590675, 36466.27067458689, 25409.364765881, 25835.095771647902, 26039.95840166372, 26309.925304154334, 26628.67298460567], "value": 20511.861286721534, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.62698555415693, 390.18840811112887, 393.65358484533607, 401.4941174120607, 413.7476139554871, 19397.612047759038, 20994.95025248739, 21645.809480478605, 22029.779702202974, 22084.22705205316, 20207.292744753937, 21070.051847666855, 21097.894510527465, 21133.0, 21402.46493837655, 5114.30956820829, 5972.4027597240365, 5994.550408719341, 6016.669083200422, 6017.548683848707, 3844.4041173618134, 4050.4936882889638, 5662.546996240291, 5858.414158584129, 5951.196588460567, 5996.3404025557375, 6015.849603759897, 6021.608595441304, 6028.336882942895, 6029.969850150743, 6138.84652883677, 6620.867582648369, 6627.900581491282, 6635.369639884203, 6637.137172167636, 5620.269364982526, 5796.769389391688, 5804.9419505805245, 5841.357450680422, 5857.238558987347, 12470.43883025266, 12476.00191984639, 12523.373831308398, 12542.310172940484, 18109.909450452797, 1660.6761681472117, 1885.679434496139, 2125.6280150973516, 2136.327056977047, 2319.9651609327357, 9978.600855965753, 10275.715535558054, 10431.5827366906, 10439.217058720626, 11114.777704445947, 1875.6248750249867, 1948.5323522354674, 1956.2859556262003, 1961.0376702116973, 2016.3648450737971, 95800.52099739526, 109127.90872091336, 118559.4072029643, 121386.99999999911, 122682.0, 11574.0, 11581.76836463266, 11651.825222621668, 11668.183227174017, 11849.111316651286, 14685.045472044283, 14822.407103715947, 14826.184559849096, 14829.258537073103, 14845.925770371296, 8579.914200858037, 8732.519711415805, 8748.8687669685, 8808.735737927873, 8858.29133669305, 8599.484030958163, 8634.827303453896, 8684.60919258635, 8750.64997400097, 8804.163604457694, 4499.267781903991, 4584.701994370355, 4594.517575654508, 4602.677812553156, 4691.812327506861, 1771.1586996176713, 1799.0156145579951, 1829.3505805439145, 1899.3447260695038, 1904.3715573860593, 933.1554942776797, 940.3558562384789, 971.9805603887883, 1004.5479534209635, 1062.9627963021258, 17090.0, 21283.255086072222, 21414.46463838385, 21434.678479822815, 22003.58944363626, 921.8202450522084, 927.3925578745932, 941.355171707369, 951.5860600638745, 966.7631430299481, 9875.308728389087, 11289.830652540219, 11396.316221026766, 11403.714907127222, 11421.37182454973, 534711.0, 540696.2285555691, 543310.0, 543869.0, 546974.0, 125537.0, 128151.0, 128281.71718282884, 128456.0, 130081.34959325333, 959.9904000960041, 969.8011907559074, 989.7030890732751, 990.1385794358954, 990.153418826907, 12522.812157817822, 18884.527886802938, 18999.145038473027, 19183.86980000868, 19228.327008554632, 51636.0, 53039.0, 53408.93182136413, 53499.0, 54828.72585637046, 73081.6345918267, 75903.0, 76092.61953690196, 77680.61159694166, 78498.6075069621, 5747.453991870766, 7376.336129748348, 8025.839483210187, 8077.919220807952, 8420.915790842013, 11617.535298587962, 12730.68173295638, 12983.156094853804, 13046.282454464716, 13312.0, 10940.12479001662, 11007.779844403227, 11352.375619340684, 11447.141464390037, 11493.597724079616, 11083.667489975314, 11208.943955280172, 11256.212065155689, 11487.54049837997, 11577.247478913843, 9644.9035509644, 11364.94317528407, 11372.0, 11586.073114150679, 11589.304641721523, 7410.814729631802, 7500.987366705425, 7520.699172033276, 7627.8855817161675, 7670.961645191738, 10723.731906702084, 11298.152638552307, 11346.375949322866, 11391.544338226378, 11482.942585287354], "value": 9383.550792453932, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1541.537538738379, 1578.9368425262987, 1766.5142085926368, 1894.138167133954, 1906.3518403742733, 8410.621522031508, 8693.652253909837, 8817.426867253624, 8846.646134154635, 8849.513276769783, 5903.793367232137, 8013.358931285497, 8431.283340916014, 8649.740507784762, 8686.522241276743, 2346.378209774411, 2378.583747844129, 2380.7738264864897, 2383.892724827378, 2384.1178763857397, 2080.9583808323823, 2345.4019225097577, 2347.5539647467012, 2387.140629373424, 2392.3660230038968, 1738.8000379956277, 2056.897155142237, 2133.7332833395826, 2367.0531787285054, 2367.3253122859987, 2295.4376177836452, 2345.44881952741, 2374.002918774119, 2391.473875747333, 2392.5334559760854, 2352.847064940773, 2368.029108065685, 2380.595298799208, 2382.7855493005713, 2392.629142482918, 8728.825423491558, 8806.163414475615, 8810.471371717653, 8818.69134580293, 8821.206091451735, 5241.109011468059, 5343.17180836967, 5385.138377859563, 5392.946070539284, 5408.378036525811, 3565.3404120237838, 3649.927001459956, 3668.046307959923, 3671.1372827385544, 3735.6465426781224, 1059.4120263253892, 1069.2461814420892, 1082.2099867097063, 1092.8797832238527, 1096.703889949709, 11415.657530274106, 11587.536498540048, 11632.418379081097, 11642.359670218219, 11679.065674746, 4577.908441831211, 4662.720236785736, 4835.540623640749, 4838.298446725275, 4845.685030473008, 9123.680671176477, 9303.116203960613, 10019.198464123, 10050.195984321237, 10073.395596264101, 5374.973125134349, 5384.461553844662, 5393.083175860113, 5397.379301380313, 5400.351957765093, 4729.290606409066, 4893.6329775266995, 4906.509349065065, 4926.113299606033, 4927.137750893579, 3778.8677396290996, 3798.924021519554, 3817.7136714746507, 3858.344081506151, 3878.4764056852528, 922.9769255768525, 973.8977407372265, 1068.529846867376, 1135.5628083188033, 1156.2311063142915, 769.330682306397, 863.9481631102154, 918.5958178401482, 919.9172074513326, 946.4699768129732, 4718.9292160617615, 5500.779968801283, 5550.750216240279, 5860.648361098348, 6028.276606807123, 901.1844280925642, 1019.9439030853226, 1042.9582816687248, 1043.3426941027105, 1050.4590136079926, 3370.4775759757276, 3817.389217725206, 3837.2325534893134, 3965.861194858166, 4028.0, 10182.898171018194, 10329.896701033043, 10765.83851242232, 10785.62250321235, 10822.296550724177, 10597.947010265054, 10716.571337146583, 10759.515821788038, 10765.515551800188, 10766.0, 7946.205379462008, 8105.189481051965, 8290.08809031006, 8297.0, 8319.292860106958, 5821.476067153976, 5923.437273459014, 5955.880882382415, 5975.40245975399, 6052.334243233153, 9942.950285248817, 10100.090991810772, 10109.747256318651, 10122.038406351385, 10125.493725313852, 9800.313978021755, 9908.801823963337, 10218.846717299395, 10223.642172524223, 10349.068583827191, 7611.505252158592, 7641.35048520882, 7751.263629955145, 7768.223177682186, 7776.41676874248, 8211.71259005932, 8341.541215233221, 8419.789505262417, 8438.987321521457, 8440.873386899078, 6266.723619028045, 7312.890306645511, 7567.394608431202, 7629.8855517168395, 7632.656530456254, 7447.069116360455, 7478.887816682863, 7532.246775322424, 7578.052743407075, 7611.923880761122, 7066.081409416743, 7066.152061752623, 7129.679164437509, 7149.7497587584185, 7154.713811447484, 4221.303484924953, 4904.828331008398, 4939.431965324034, 4967.155583550732, 5072.644914855925, 4385.276429389115, 4971.875703107451, 4980.626453016111, 5027.095122877917, 5190.299309593308], "value": 4559.322894992393, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.6674841049051, 313.4092236134885, 313.836804861472, 320.49042023183137, 324.5764277617708, 4758.310045043467, 5344.465553444654, 5360.571154307656, 5366.248725178469, 5366.436524164965, 5200.583953283728, 5232.686038837673, 5323.494268044539, 5327.147656374981, 5407.270018547495, 3806.866759663419, 3854.5181852268465, 3951.5850835662127, 4091.89770255743, 4097.180563887235, 4016.176683779818, 4032.5765794591584, 4056.553779084313, 4086.7331127350617, 4118.526369467505, 4013.438118663375, 4026.7382620129597, 4034.899127521806, 4050.96700341414, 4066.552679205285, 3459.3395170318263, 3648.2521083177885, 3932.292187406266, 3983.860564880244, 4058.5129784425762, 2820.7884408669433, 2959.8076125052016, 3016.1403999859936, 3481.234128491728, 3495.5106285120105, 4438.778061096932, 5042.697438153686, 5075.593952483792, 5098.209777484496, 5112.284280200774, 4259.297215959363, 4591.9081618367445, 4617.58441740245, 4627.167109920213, 4647.744374059425, 5558.722063896829, 5561.332640083176, 5562.582806289544, 5589.636673616202, 5598.356189038272, 3732.253549290153, 3842.7117966152373, 3849.5957924418094, 3860.613938606145, 3864.632859878307, 4993.90012199754, 5408.377486753958, 5492.697901615409, 5647.604667673223, 5907.995640741085, 3532.523109380252, 3578.7673801202836, 3853.672437842815, 3881.146147847469, 3892.474515940312, 3321.5848018997626, 3535.7524973251625, 3852.97896057545, 3923.862664806718, 3939.290927632996, 4235.682323825726, 4271.572842715704, 4581.0, 4582.381378513858, 4589.2657174851865, 4671.197281630994, 5177.964407118591, 5582.985187998516, 5593.328800543961, 5622.465865742748, 1316.3484075382555, 1344.5764584156154, 1347.47448495087, 1351.6215459671307, 1353.2489468344966, 792.5957761541655, 807.0, 830.7424698343533, 832.696065935929, 835.9801042727862, 4990.401151861727, 5082.009008243357, 5218.347706536683, 5253.973730131402, 5479.643823151482, 5717.828465146053, 5725.59920805548, 5727.971360143172, 5735.483806457355, 5784.0, 4163.896567409578, 5112.667676601009, 5155.871103222375, 5223.451537588575, 5293.0, 2994.1616347422755, 3051.252443151442, 3057.4955132403347, 3060.8010479318773, 3067.9846600766855, 5936.7922122725495, 5989.011813050885, 6006.249218847644, 6024.879502409927, 6025.276966764016, 5497.202905578669, 5838.897996721177, 5841.240638716939, 5873.412658734178, 5939.287285525678, 5858.56060795442, 5893.764249430061, 5930.347661757117, 5930.644161350333, 5983.282006159288, 4957.380327459067, 5092.414372347227, 5405.72971351423, 5745.1382292656435, 5761.193432919311, 4716.481187069488, 5662.631928924607, 5677.318721753416, 5695.316562012585, 5789.9710501447225, 5034.798608055637, 5548.223248745259, 6064.0, 6082.847928801815, 6120.908186377119, 2984.820910745362, 3821.1211421373796, 3841.8079096045644, 3873.7797593759005, 3935.134270460494, 4541.455025396974, 5653.406392328746, 5688.943110569007, 5744.569157313134, 5759.510441612342, 5537.889242215214, 5570.136628822539, 5575.581831362745, 5580.469855363734, 5583.581231407742, 4329.870103896888, 4565.497795242454, 5212.166053431432, 5361.839144825662, 5462.289902312674, 4646.62826973848, 4649.721016739007, 4697.060587882369, 4767.976160119316, 5296.443873393238, 4906.607471402208, 5438.564914806885, 5568.303962004749, 5599.272094627672, 5652.547796176378, 5494.862628434321, 5528.723563821713, 5531.668099914018, 5536.4186760389575, 5580.21876937221], "value": 4066.8695968728885, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [737.2185483387608, 752.65377926154, 790.735103740247, 858.831988495646, 858.9312854971595, 15414.075155490678, 16494.505164845043, 16515.0, 16538.173091345445, 16544.75182872258, 14727.705445891075, 15040.09759414436, 15060.0, 15091.773623395631, 15098.773518397233, 690.261420280299, 718.3068339052813, 871.1549796697194, 906.8685040669098, 907.9092090790934, 737.2516895351223, 891.0510306523531, 892.2549671024702, 893.4415990006246, 895.0870112485261, 938.8638647396124, 939.7462685075026, 940.2947789158131, 941.6704153546273, 942.2885721280423, 734.3721118443707, 764.598585742484, 909.4361495872536, 937.334492510315, 951.362587066667, 703.388052394415, 703.707961196103, 703.9472039597, 703.9612821294827, 705.3722187253348, 15071.171085590287, 15078.623034424118, 15107.32017059235, 15131.621709457242, 15152.0, 14081.859181408157, 14166.433342666282, 14175.0, 14192.864570834308, 14308.856911430856, 13105.803412948813, 13193.274369909652, 13197.27414992175, 13224.404901779442, 13613.591592252249, 4537.863864084083, 4605.263157894721, 4675.672702910798, 4686.367340409035, 4785.904281914342, 110297.0, 110774.44612776884, 110789.0, 110805.44597276962, 110837.0, 10919.924545627016, 11057.447127643747, 11067.778644427066, 11113.999740023275, 11268.380239086766, 22008.0, 22269.888650556644, 22288.885555722474, 22304.55390892173, 22311.888440558017, 7189.712411503481, 7868.881966770388, 8005.319547838499, 8143.348532117417, 8188.14024527416, 4452.888677783017, 4473.4408198975125, 4497.325401189848, 4498.613851494832, 4640.79116439761, 3437.2094418283937, 3946.309395855714, 4128.669706423479, 4129.463169787909, 4140.0, 721.6896734404152, 738.2949283434307, 741.6032422653855, 748.2666986353296, 750.6396929473883, 560.9242752228479, 675.7700984208693, 680.6664734280167, 684.736376495053, 702.3117345001926, 9688.0, 10117.848232276521, 10198.359641595645, 10434.37393756377, 10561.039610146196, 637.1173183243504, 655.2661019658013, 682.3449488491005, 684.1174884399169, 687.7077242172057, 4496.975207189547, 4616.722996620214, 4631.212693842055, 4668.323093151474, 4674.345591617176, 116835.41582292205, 120142.0, 136314.0, 145943.0, 146282.0, 125077.0, 125238.12142817865, 126040.0, 126725.36637316938, 128211.0, 65825.67087164629, 65937.34062659409, 89130.0, 104510.0, 106657.0, 9633.133018028411, 9706.611735530782, 9796.262330838306, 9887.258455615714, 9939.204863611038, 57673.55816104463, 58840.411595885205, 60044.099338509084, 60719.0, 61034.0, 94274.52862735643, 97945.51027244818, 98622.0, 98622.0, 100680.91828980569, 13361.799573006218, 13377.464901403835, 13437.798433023709, 13438.328083595976, 13535.187888726708, 11898.0, 14892.0, 15011.399544018117, 15048.172350520827, 15094.924525377302, 14041.859581404055, 14330.928345358207, 14353.210573418562, 14369.425222990963, 14393.280335983369, 13985.160890346611, 14098.647533811327, 14230.430782768573, 14343.426262949783, 14464.710705786036, 13844.307784610528, 14028.22844743508, 14087.929560352133, 14381.424743010162, 14751.0, 11229.7754044917, 11529.884701152881, 11591.130665199975, 11591.826122608, 11758.94120529426, 11137.888621113685, 11232.213745037769, 11474.541018359507, 11780.941095294469, 11878.287302761863], "value": 8037.177457607466, "unit": "score"}], "name": "dromaeo_css", "value": 7386.502236998779, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 113.0, 112.0, 114.0, 113.0, 112.0, 115.0, 113.0, 112.0, 114.0], "value": 112.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 110.0, 107.0, 161.0, 106.0, 134.0, 134.0, 106.0, 160.0, 107.0], "value": 126.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 183.0, 184.0, 185.0, 184.0, 182.0, 195.0, 198.0, 184.0, 182.0], "value": 186.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 57.0, 56.0, 107.0, 58.0, 58.0, 60.0, 63.0, 97.0, 57.0], "value": 69.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 70.0, 92.0, 70.0, 73.0, 67.0, 98.0, 69.0, 67.0, 98.0], "value": 78.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 88.0, 91.0, 87.0, 91.0, 88.0, 90.0, 88.0, 91.0, 86.0], "value": 89.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 96.0, 95.0, 96.0, 95.0, 96.0, 94.0, 95.0], "value": 95.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 87.0, 88.0, 102.0, 142.0, 141.0, 86.0, 89.0, 83.0], "value": 99.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 55.0, 54.0, 53.0, 53.0, 54.0, 53.0, 52.0, 54.0], "value": 53.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 78.0, 80.0, 72.0, 72.0, 71.0, 71.0, 70.0, 72.0], "value": 71.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 83.0, 83.0, 84.0, 82.0, 81.0, 82.0, 85.0, 79.0], "value": 82.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 146.0, 140.0, 142.0, 146.0, 148.0, 137.0, 135.0, 175.0, 135.0], "value": 144.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 174.0, 172.0, 173.0, 172.0, 174.0, 173.0, 171.0, 170.0, 171.0], "value": 174.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 68.0, 70.0, 68.0, 69.0, 69.0, 84.0, 85.0, 84.0], "value": 73.5, "unit": "score"}], "name": "kraken", "value": 1457.1, "alertThreshold": 2.0}]} 04:11:16 INFO - Return code: 0 04:11:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:11:16 INFO - # TBPL SUCCESS # 04:11:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:11:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:11:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:11:16 INFO - 2016-07-18 04:11:16.801 screenresolution[1964:16880] starting screenresolution argv=screenresolution get 04:11:16 INFO - 2016-07-18 04:11:16.803 screenresolution[1964:16880] Display 0: 1600x1200x32@60 04:11:16 INFO - 2016-07-18 04:11:16.809 screenresolution[1965:16883] starting screenresolution argv=screenresolution list 04:11:16 INFO - Available Modes on Display 0 04:11:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:11:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:11:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:11:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:11:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:11:17 INFO - Intel Iris: 04:11:17 INFO - Chipset Model: Intel Iris 04:11:17 INFO - Type: GPU 04:11:17 INFO - Bus: Built-In 04:11:17 INFO - VRAM (Dynamic, Max): 1536 MB 04:11:17 INFO - Vendor: Intel (0x8086) 04:11:17 INFO - Device ID: 0x0a2e 04:11:17 INFO - Revision ID: 0x0009 04:11:17 INFO - Displays: 04:11:17 INFO - Display: 04:11:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:11:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:11:17 INFO - Display Serial Number: 1600x1200 60 04:11:17 INFO - Main Display: Yes 04:11:17 INFO - Mirror: Off 04:11:17 INFO - Online: Yes 04:11:17 INFO - Rotation: Supported 04:11:17 INFO - Return code: 0 04:11:17 INFO - Running post-action listener: _resource_record_post_action 04:11:17 INFO - ##### 04:11:17 INFO - ##### Finished run-tests step (success) 04:11:17 INFO - ##### 04:11:17 INFO - Running post-run listener: _resource_record_post_run 04:11:17 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 1314816; Write bytes: 703739904; Read time: 13; Write time: 1962 04:11:17 INFO - TinderboxPrint: CPU usage
53.3% 04:11:17 INFO - TinderboxPrint: I/O read bytes / time
1,314,816 / 13 04:11:17 INFO - TinderboxPrint: I/O write bytes / time
703,739,904 / 1,962 04:11:17 INFO - TinderboxPrint: CPU idle
2,264.6 (46.7%) 04:11:17 INFO - TinderboxPrint: CPU system
964.3 (19.9%) 04:11:17 INFO - TinderboxPrint: CPU user
1,620.7 (33.4%) 04:11:17 INFO - TinderboxPrint: Swap in / out
408,543,232 / 0 04:11:17 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 402854400; Write bytes: 396662784; Read time: 30763; Write time: 855 04:11:17 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 1277952; Write bytes: 293326848; Read time: 13; Write time: 1065 04:11:17 INFO - Running post-run listener: _upload_blobber_files 04:11:17 INFO - Blob upload gear active. 04:11:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:11:17 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 04:11:17 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'] 04:11:17 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 04:11:17 INFO - (blobuploader) - INFO - Open directory for files ... 04:11:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:11:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:11:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:11:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:11:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:11:18 INFO - (blobuploader) - INFO - Done attempting. 04:11:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:11:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:11:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:11:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:11:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:11:19 INFO - (blobuploader) - INFO - Done attempting. 04:11:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:11:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:11:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:11:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:11:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:11:22 INFO - (blobuploader) - INFO - Done attempting. 04:11:22 INFO - (blobuploader) - INFO - Iteration through files over. 04:11:22 INFO - Return code: 0 04:11:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:11:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:11:22 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e01cacfdb782ec64993d2883a09197fcdb1add9807905e11283a2ffe329f0e639c58bfea5cd5fef3c2642fe00da11797e33b2aa770722231febd61261c51ef66", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/041145e37275350ab75e2e350f2f93604640e5399a38de07693e59c0641a2382459177999f8f02da77f8ff7a8d46b6f201076d7aa2e77ce3e79dc75324fbac1f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/44cf5f327cc22ee79acd36599a1dea01457e9b755d6226e5c778bcdbd3cbebb2757bd3ab0e59935473ddcb53623a69e4dab40aa06d2e7ab6405ab2826bd7a6a4"} 04:11:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:11:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:11:22 INFO - Contents: 04:11:22 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e01cacfdb782ec64993d2883a09197fcdb1add9807905e11283a2ffe329f0e639c58bfea5cd5fef3c2642fe00da11797e33b2aa770722231febd61261c51ef66", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/041145e37275350ab75e2e350f2f93604640e5399a38de07693e59c0641a2382459177999f8f02da77f8ff7a8d46b6f201076d7aa2e77ce3e79dc75324fbac1f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/44cf5f327cc22ee79acd36599a1dea01457e9b755d6226e5c778bcdbd3cbebb2757bd3ab0e59935473ddcb53623a69e4dab40aa06d2e7ab6405ab2826bd7a6a4"} 04:11:22 INFO - Running post-run listener: copy_logs_to_upload_dir 04:11:22 INFO - Copying logs to upload dir... 04:11:22 INFO - mkdir: /builds/slave/test/build/upload/logs 04:11:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.500542 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-07-18 04:11:22.683862) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-18 04:11:22.687328) ========= 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.oyYVhc0Twj/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.Pfcyrg6P5R/Listeners TMPDIR=/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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/e01cacfdb782ec64993d2883a09197fcdb1add9807905e11283a2ffe329f0e639c58bfea5cd5fef3c2642fe00da11797e33b2aa770722231febd61261c51ef66", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/041145e37275350ab75e2e350f2f93604640e5399a38de07693e59c0641a2382459177999f8f02da77f8ff7a8d46b6f201076d7aa2e77ce3e79dc75324fbac1f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/44cf5f327cc22ee79acd36599a1dea01457e9b755d6226e5c778bcdbd3cbebb2757bd3ab0e59935473ddcb53623a69e4dab40aa06d2e7ab6405ab2826bd7a6a4"} build_url:https://queue.taskcluster.net/v1/task/fk14sDrSRz2NK8krTQ9aYA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010975 build_url: 'https://queue.taskcluster.net/v1/task/fk14sDrSRz2NK8krTQ9aYA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e01cacfdb782ec64993d2883a09197fcdb1add9807905e11283a2ffe329f0e639c58bfea5cd5fef3c2642fe00da11797e33b2aa770722231febd61261c51ef66", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/041145e37275350ab75e2e350f2f93604640e5399a38de07693e59c0641a2382459177999f8f02da77f8ff7a8d46b6f201076d7aa2e77ce3e79dc75324fbac1f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/44cf5f327cc22ee79acd36599a1dea01457e9b755d6226e5c778bcdbd3cbebb2757bd3ab0e59935473ddcb53623a69e4dab40aa06d2e7ab6405ab2826bd7a6a4"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-18 04:11:22.724478) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 04:11:22.725242) ========= 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.oyYVhc0Twj/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.Pfcyrg6P5R/Listeners TMPDIR=/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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.005235 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-18 04:11:22.776211) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-18 04:11:22.776919) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-18 04:11:22.777569) ========= ========= Total master_lag: 0.19 =========