builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0104 starttime: 1468605129.9 results: success (0) buildid: 20160713141046 builduid: f56c373fb50a40f583c4b414d4dcca39 revision: 5fd14a66be3191e5628af3cfb0a456dd70f179b8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-15 10:52:09.898679) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-15 10:52:09.899078) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-15 10:52:09.899392) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-15 10:52:10.020819) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 10:52:10.021133) ========= 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.4c9kCLuys8/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.DyQ0j1EWqH/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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-15 10:52:10-- 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% 9.40M=0.001s 2016-07-15 10:52:10 (9.40 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.146996 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 10:52:10.193818) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 10:52:10.194136) ========= 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.4c9kCLuys8/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.DyQ0j1EWqH/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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.027164 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 10:52:10.243707) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 10:52:10.244057) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5fd14a66be3191e5628af3cfb0a456dd70f179b8 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 5fd14a66be3191e5628af3cfb0a456dd70f179b8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4c9kCLuys8/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.DyQ0j1EWqH/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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-15 10:52:10,322 truncating revision to first 12 chars 2016-07-15 10:52:10,322 Setting DEBUG logging. 2016-07-15 10:52:10,322 attempt 1/10 2016-07-15 10:52:10,322 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5fd14a66be31?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-15 10:52:10,987 unpacking tar archive at: mozilla-central-5fd14a66be31/testing/mozharness/ program finished with exit code 0 elapsedTime=0.925242 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 10:52:11.191729) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-15 10:52:11.192026) ========= script_repo_revision: 5fd14a66be3191e5628af3cfb0a456dd70f179b8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-15 10:52:11.192706) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-15 10:52:11.192971) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-15 10:52:11.212802) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2016-07-15 10:52:11.213298) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4c9kCLuys8/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.DyQ0j1EWqH/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 10:52:11 INFO - MultiFileLogger online at 20160715 10:52:11 in /builds/slave/test 10:52:11 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 10:52:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:52:11 INFO - {'append_to_log': False, 10:52:11 INFO - 'base_work_dir': '/builds/slave/test', 10:52:11 INFO - 'blob_upload_branch': 'Firefox', 10:52:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:52:11 INFO - 'branch': 'Firefox', 10:52:11 INFO - 'buildbot_json_path': 'buildprops.json', 10:52:11 INFO - 'config_files': ('talos/mac_config.py',), 10:52:11 INFO - 'default_actions': ('clobber', 10:52:11 INFO - 'read-buildbot-config', 10:52:11 INFO - 'download-and-extract', 10:52:11 INFO - 'populate-webroot', 10:52:11 INFO - 'create-virtualenv', 10:52:11 INFO - 'install', 10:52:11 INFO - 'run-tests'), 10:52:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:52:11 INFO - 'download_minidump_stackwalk': True, 10:52:11 INFO - 'download_symbols': 'ondemand', 10:52:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:52:11 INFO - 'tooltool.py': '/tools/tooltool.py', 10:52:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:52:11 INFO - '/tools/misc-python/virtualenv.py')}, 10:52:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:52:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:52:11 INFO - 'installer_path': 'installer.exe', 10:52:11 INFO - 'log_level': 'info', 10:52:11 INFO - 'log_name': 'talos', 10:52:11 INFO - 'log_to_console': True, 10:52:11 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:52:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:52:11 INFO - 'opt_config_files': (), 10:52:11 INFO - 'pip_index': False, 10:52:11 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:52:11 INFO - 'cmd': ('bash', 10:52:11 INFO - '-c', 10:52:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:52:11 INFO - 'enabled': True, 10:52:11 INFO - 'halt_on_failure': False, 10:52:11 INFO - 'name': 'check_screen_resolution'},), 10:52:11 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:52:11 INFO - 'cmd': ('bash', 10:52:11 INFO - '-c', 10:52:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:52:11 INFO - 'enabled': True, 10:52:11 INFO - 'halt_on_failure': False, 10:52:11 INFO - 'name': 'check_screen_resolution'},), 10:52:11 INFO - 'run_cmd_checks_enabled': True, 10:52:11 INFO - 'sps_profile': False, 10:52:11 INFO - 'sps_profile_interval': 0, 10:52:11 INFO - 'suite': 'dromaeojs', 10:52:11 INFO - 'system_bits': '32', 10:52:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:52:11 INFO - 'title': 't-yosemite-r7-0104', 10:52:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:52:11 INFO - 'use_talos_json': True, 10:52:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:52:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:52:11 INFO - 'work_dir': 'build'} 10:52:11 INFO - ##### 10:52:11 INFO - ##### Running clobber step. 10:52:11 INFO - ##### 10:52:11 INFO - Running pre-action listener: _resource_record_pre_action 10:52:11 INFO - Running main action method: clobber 10:52:11 INFO - rmtree: /builds/slave/test/build 10:52:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:52:13 INFO - Running post-action listener: _resource_record_post_action 10:52:13 INFO - ##### 10:52:13 INFO - ##### Finished clobber step (success) 10:52:13 INFO - ##### 10:52:13 INFO - ##### 10:52:13 INFO - ##### Running read-buildbot-config step. 10:52:13 INFO - ##### 10:52:13 INFO - Running pre-action listener: _resource_record_pre_action 10:52:13 INFO - Running main action method: read_buildbot_config 10:52:13 INFO - Using buildbot properties: 10:52:13 INFO - { 10:52:13 INFO - "project": "", 10:52:13 INFO - "product": "firefox", 10:52:13 INFO - "script_repo_revision": "production", 10:52:13 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 10:52:13 INFO - "repository": "", 10:52:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 10:52:13 INFO - "buildid": "20160713141046", 10:52:13 INFO - "pgo_build": "False", 10:52:13 INFO - "basedir": "/builds/slave/test", 10:52:13 INFO - "buildnumber": 15, 10:52:13 INFO - "slavename": "t-yosemite-r7-0104", 10:52:13 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 10:52:13 INFO - "platform": "yosemite_r7", 10:52:13 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 10:52:13 INFO - "branch": "mozilla-central", 10:52:13 INFO - "repo_path": "mozilla-central", 10:52:13 INFO - "revision": "5fd14a66be3191e5628af3cfb0a456dd70f179b8", 10:52:13 INFO - "stage_platform": "macosx64", 10:52:13 INFO - "builduid": "f56c373fb50a40f583c4b414d4dcca39", 10:52:13 INFO - "slavebuilddir": "test" 10:52:13 INFO - } 10:52:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 10:52:13 INFO - Running post-action listener: _resource_record_post_action 10:52:13 INFO - ##### 10:52:13 INFO - ##### Finished read-buildbot-config step (success) 10:52:13 INFO - ##### 10:52:13 INFO - ##### 10:52:13 INFO - ##### Running download-and-extract step. 10:52:13 INFO - ##### 10:52:13 INFO - Running pre-action listener: _resource_record_pre_action 10:52:13 INFO - Running main action method: download_and_extract 10:52:13 INFO - mkdir: /builds/slave/test/build/tests 10:52:13 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')]} 10:52:13 INFO - https://queue.taskcluster.net/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 10:52:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 10:52:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 10:52:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbB3eD1KQ8qHxsobdL1-xA/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 10:52:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbB3eD1KQ8qHxsobdL1-xA/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 10:52:21 INFO - Downloaded 1199 bytes. 10:52:21 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 10:52:21 INFO - Using the following test package requirements: 10:52:21 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 10:52:21 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:52:21 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 10:52:21 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:52:21 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 10:52:21 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:52:21 INFO - u'jsshell-mac.zip'], 10:52:21 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:52:21 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 10:52:21 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 10:52:21 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:52:21 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 10:52:21 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:52:21 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 10:52:21 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:52:21 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 10:52:21 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:52:21 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 10:52:21 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:52:21 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')]} 10:52:21 INFO - https://queue.taskcluster.net/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:52:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:52:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:52:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbB3eD1KQ8qHxsobdL1-xA/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 10:52:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbB3eD1KQ8qHxsobdL1-xA/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 10:52:25 INFO - Downloaded 18827271 bytes. 10:52: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'] 10:52: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 10:52:28 INFO - Return code: 0 10:52:28 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 10:52:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:52:28 INFO - https://queue.taskcluster.net/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:52:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:52:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:52:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbB3eD1KQ8qHxsobdL1-xA/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 10:52:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbB3eD1KQ8qHxsobdL1-xA/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 10:52:35 INFO - Downloaded 18827271 bytes. 10:52:35 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'] 10:52:35 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 10:52:39 INFO - Return code: 0 10:52:39 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')]} 10:52:39 INFO - https://queue.taskcluster.net/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 10:52:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 10:52:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 10:52:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbB3eD1KQ8qHxsobdL1-xA/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 10:52:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbB3eD1KQ8qHxsobdL1-xA/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 10:52:40 INFO - Downloaded 14403317 bytes. 10:52:40 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'] 10:52:40 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 10:52:40 INFO - Return code: 0 10:52:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:52:40 INFO - https://queue.taskcluster.net/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 10:52:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 10:52:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 10:52:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 10:52:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AbB3eD1KQ8qHxsobdL1-xA/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 10:52:44 INFO - Downloaded 112779863 bytes. 10:52:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 10:52:44 INFO - mkdir: /builds/slave/test/properties 10:52:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:52:44 INFO - Writing to file /builds/slave/test/properties/build_url 10:52:44 INFO - Contents: 10:52:44 INFO - build_url:https://queue.taskcluster.net/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 10:52:44 INFO - Running post-action listener: _resource_record_post_action 10:52:44 INFO - Running post-action listener: set_extra_try_arguments 10:52:44 INFO - ##### 10:52:44 INFO - ##### Finished download-and-extract step (success) 10:52:44 INFO - ##### 10:52:44 INFO - ##### 10:52:44 INFO - ##### Running populate-webroot step. 10:52:44 INFO - ##### 10:52:44 INFO - Running pre-action listener: _resource_record_pre_action 10:52:44 INFO - Running main action method: populate_webroot 10:52:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:52:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:52:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:52:44 INFO - '--tppagecycles', 10:52:44 INFO - '7'], 10:52:44 INFO - 'tests': ['tsvgm']}}, 10:52:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:52:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:52:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:52:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:52:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:52:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:52:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:52:44 INFO - 'talos_options': ['--disable-e10s'], 10:52:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:52:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:52:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:52:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:52:44 INFO - 'talos_options': ['--disable-e10s'], 10:52:44 INFO - 'tests': ['damp', 'tps']}, 10:52:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:52:44 INFO - 'tests': ['damp', 'tps']}, 10:52:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:52:44 INFO - 'tests': ['dromaeo_dom']}, 10:52:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:52:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:52:44 INFO - 'tests': ['basic_compositor_video']}, 10:52:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:52:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:52:44 INFO - 'tests': ['a11yr', 10:52:44 INFO - 'ts_paint', 10:52:44 INFO - 'tpaint', 10:52:44 INFO - 'sessionrestore', 10:52:44 INFO - 'sessionrestore_no_auto_restore', 10:52:44 INFO - 'tabpaint']}, 10:52:44 INFO - 'other-e10s': {'tests': ['a11yr', 10:52:44 INFO - 'ts_paint', 10:52:44 INFO - 'tpaint', 10:52:44 INFO - 'sessionrestore', 10:52:44 INFO - 'sessionrestore_no_auto_restore', 10:52:44 INFO - 'tabpaint']}, 10:52:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:52:44 INFO - 'ts_paint', 10:52:44 INFO - 'tpaint', 10:52:44 INFO - 'sessionrestore', 10:52:44 INFO - 'sessionrestore_no_auto_restore', 10:52:44 INFO - 'tabpaint']}, 10:52:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:52:44 INFO - 'ts_paint', 10:52:44 INFO - 'tpaint', 10:52:44 INFO - 'sessionrestore', 10:52:44 INFO - 'sessionrestore_no_auto_restore', 10:52:44 INFO - 'tabpaint']}, 10:52:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:52:44 INFO - 'tests': ['a11yr', 10:52:44 INFO - 'ts_paint', 10:52:44 INFO - 'tpaint', 10:52:44 INFO - 'sessionrestore', 10:52:44 INFO - 'sessionrestore_no_auto_restore', 10:52:44 INFO - 'tabpaint']}, 10:52:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:52:44 INFO - 'tests': ['a11yr', 10:52:44 INFO - 'ts_paint', 10:52:44 INFO - 'tpaint', 10:52:44 INFO - 'sessionrestore', 10:52:44 INFO - 'sessionrestore_no_auto_restore', 10:52:44 INFO - 'tabpaint']}, 10:52:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:52:44 INFO - 'tests': ['tsvgx', 10:52:44 INFO - 'tsvgr_opacity', 10:52:44 INFO - 'tart', 10:52:44 INFO - 'tscrollx', 10:52:44 INFO - 'cart']}, 10:52:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:52:44 INFO - 'tsvgr_opacity', 10:52:44 INFO - 'tart', 10:52:44 INFO - 'tscrollx', 10:52:44 INFO - 'cart']}, 10:52:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:52:44 INFO - 'talos_options': ['--disable-e10s'], 10:52:44 INFO - 'tests': ['tp5o']}, 10:52:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:52:44 INFO - 'tests': ['tp5o']}, 10:52:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:52:44 INFO - 'talos_options': ['--disable-e10s', 10:52:44 INFO - '--xperf_path', 10:52:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:52:44 INFO - 'tests': ['tp5n']}, 10:52:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:52:44 INFO - 'talos_options': ['--xperf_path', 10:52:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:52:44 INFO - 'tests': ['tp5n']}}, 10:52:44 INFO - 'talos.zip': {'path': '', 10:52:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:52:44 INFO - Running post-action listener: _resource_record_post_action 10:52:44 INFO - ##### 10:52:44 INFO - ##### Finished populate-webroot step (success) 10:52:44 INFO - ##### 10:52:44 INFO - ##### 10:52:44 INFO - ##### Running create-virtualenv step. 10:52:44 INFO - ##### 10:52:44 INFO - Running pre-action listener: _resource_record_pre_action 10:52:44 INFO - Running main action method: create_virtualenv 10:52:44 INFO - Creating virtualenv /builds/slave/test/build/venv 10:52:44 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 10:52:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:52:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:52:45 INFO - Using real prefix '/tools/python27' 10:52:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:52:45 INFO - Installing distribute.............................................................................................................................................................................................done. 10:52:48 INFO - Installing pip.................done. 10:52:48 INFO - Return code: 0 10:52:48 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:52:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:52:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:52:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:52:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:52:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108c5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108cf3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ac94a52d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bc2d30>, '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 0x108927db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bbab20>, '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.4c9kCLuys8/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.DyQ0j1EWqH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 10:52:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:52:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:52:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4c9kCLuys8/Render', 10:52:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:52:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:52:48 INFO - 'HOME': '/Users/cltbld', 10:52:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:52:48 INFO - 'LOGNAME': 'cltbld', 10:52:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:48 INFO - 'MOZ_NO_REMOTE': '1', 10:52:48 INFO - 'NO_EM_RESTART': '1', 10:52:48 INFO - 'PAGER': '/bin/cat', 10:52:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:52:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:48 INFO - 'PWD': '/builds/slave/test', 10:52:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:52:48 INFO - 'SHELL': '/bin/bash', 10:52:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DyQ0j1EWqH/Listeners', 10:52:48 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 10:52:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:52:48 INFO - 'USER': 'cltbld', 10:52:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:52:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:52:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:48 INFO - 'XPC_FLAGS': '0x0', 10:52:48 INFO - 'XPC_SERVICE_NAME': '0', 10:52:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:52:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:52:49 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 10:52:49 INFO - Cleaning up... 10:52:49 INFO - Return code: 0 10:52:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:52: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')]} 10:52:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:52:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52: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')]} 10:52:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:52:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52: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', '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 0x108c5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108cf3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ac94a52d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bc2d30>, '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 0x108927db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bbab20>, '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.4c9kCLuys8/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.DyQ0j1EWqH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 10:52: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', 'psutil>=3.1.1'] in /builds/slave/test/build 10:52: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 psutil>=3.1.1 10:52:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4c9kCLuys8/Render', 10:52:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:52:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:52:49 INFO - 'HOME': '/Users/cltbld', 10:52:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:52:49 INFO - 'LOGNAME': 'cltbld', 10:52:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:49 INFO - 'MOZ_NO_REMOTE': '1', 10:52:49 INFO - 'NO_EM_RESTART': '1', 10:52:49 INFO - 'PAGER': '/bin/cat', 10:52:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:52:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:49 INFO - 'PWD': '/builds/slave/test', 10:52:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:52:49 INFO - 'SHELL': '/bin/bash', 10:52:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DyQ0j1EWqH/Listeners', 10:52:49 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 10:52:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:52:49 INFO - 'USER': 'cltbld', 10:52:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:52:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:52:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:49 INFO - 'XPC_FLAGS': '0x0', 10:52:49 INFO - 'XPC_SERVICE_NAME': '0', 10:52:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:52:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:52:49 INFO - Downloading/unpacking psutil>=3.1.1 10:52: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 10:52: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 10:52: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 10:52: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 10:52: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 10:52: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 10:52:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:52:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:52:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:52:52 INFO - Installing collected packages: psutil 10:52:52 INFO - Running setup.py install for psutil 10:52:52 INFO - building 'psutil._psutil_osx' extension 10:52:52 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 10:52:53 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 10:52:53 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 10:52:53 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 10:52:53 INFO - building 'psutil._psutil_posix' extension 10:52:53 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 10:52:53 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:52:53 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:52:53 INFO - ^ 10:52:53 INFO - 1 warning generated. 10:52:53 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 10:52:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:52:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:52:53 INFO - Successfully installed psutil 10:52:53 INFO - Cleaning up... 10:52:53 INFO - Return code: 0 10:52:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:52:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:52:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:52:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:52:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:52:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:53 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 0x108c5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108cf3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ac94a52d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bc2d30>, '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 0x108927db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bbab20>, '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.4c9kCLuys8/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.DyQ0j1EWqH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 10:52:53 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 10:52:53 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 10:52:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4c9kCLuys8/Render', 10:52:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:52:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:52:53 INFO - 'HOME': '/Users/cltbld', 10:52:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:52:53 INFO - 'LOGNAME': 'cltbld', 10:52:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:53 INFO - 'MOZ_NO_REMOTE': '1', 10:52:53 INFO - 'NO_EM_RESTART': '1', 10:52:53 INFO - 'PAGER': '/bin/cat', 10:52:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:52:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:53 INFO - 'PWD': '/builds/slave/test', 10:52:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:52:53 INFO - 'SHELL': '/bin/bash', 10:52:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DyQ0j1EWqH/Listeners', 10:52:53 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 10:52:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:52:53 INFO - 'USER': 'cltbld', 10:52:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:52:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:52:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:53 INFO - 'XPC_FLAGS': '0x0', 10:52:53 INFO - 'XPC_SERVICE_NAME': '0', 10:52:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:52:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:52:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:52:54 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 10:52:54 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 10:52:54 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 10:52:54 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 10:52:54 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 10:52:54 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 10:52:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:52:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:52:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:52:56 INFO - Installing collected packages: mozsystemmonitor 10:52:56 INFO - Running setup.py install for mozsystemmonitor 10:52:56 INFO - Successfully installed mozsystemmonitor 10:52:56 INFO - Cleaning up... 10:52:57 INFO - Return code: 0 10:52:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:52:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:52:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:52:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:52:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:52:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:52:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108c5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108cf3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ac94a52d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bc2d30>, '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 0x108927db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bbab20>, '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.4c9kCLuys8/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.DyQ0j1EWqH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 10:52:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:52:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:52:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4c9kCLuys8/Render', 10:52:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:52:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:52:57 INFO - 'HOME': '/Users/cltbld', 10:52:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:52:57 INFO - 'LOGNAME': 'cltbld', 10:52:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:52:57 INFO - 'MOZ_NO_REMOTE': '1', 10:52:57 INFO - 'NO_EM_RESTART': '1', 10:52:57 INFO - 'PAGER': '/bin/cat', 10:52:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:52:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:52:57 INFO - 'PWD': '/builds/slave/test', 10:52:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:52:57 INFO - 'SHELL': '/bin/bash', 10:52:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DyQ0j1EWqH/Listeners', 10:52:57 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 10:52:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:52:57 INFO - 'USER': 'cltbld', 10:52:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:52:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:52:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:52:57 INFO - 'XPC_FLAGS': '0x0', 10:52:57 INFO - 'XPC_SERVICE_NAME': '0', 10:52:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:52:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:52:57 INFO - Downloading/unpacking jsonschema==2.5.1 10:52:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:52:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:52:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:52:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:52:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:52:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:53:00 INFO - Downloading functools32-3.2.3-2.tar.gz 10:53:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:53:00 INFO - warning: no files found matching '*.txt' 10:53:00 INFO - no previously-included directories found matching 'build' 10:53:00 INFO - no previously-included directories found matching 'dist' 10:53:00 INFO - no previously-included directories found matching '.git*' 10:53:00 INFO - Installing collected packages: jsonschema, functools32 10:53:00 INFO - Running setup.py install for functools32 10:53:00 INFO - warning: no files found matching '*.txt' 10:53:00 INFO - no previously-included directories found matching 'build' 10:53:00 INFO - no previously-included directories found matching 'dist' 10:53:00 INFO - no previously-included directories found matching '.git*' 10:53:00 INFO - Successfully installed jsonschema functools32 10:53:00 INFO - Cleaning up... 10:53:00 INFO - Return code: 0 10:53:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:53:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:53:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:53:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:53:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:53:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:00 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 0x108c5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108cf3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ac94a52d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bc2d30>, '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 0x108927db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bbab20>, '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.4c9kCLuys8/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.DyQ0j1EWqH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 10:53:00 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 10:53:00 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 10:53:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4c9kCLuys8/Render', 10:53:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:53:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:53:00 INFO - 'HOME': '/Users/cltbld', 10:53:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:53:00 INFO - 'LOGNAME': 'cltbld', 10:53:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:53:00 INFO - 'MOZ_NO_REMOTE': '1', 10:53:00 INFO - 'NO_EM_RESTART': '1', 10:53:00 INFO - 'PAGER': '/bin/cat', 10:53:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:53:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:53:00 INFO - 'PWD': '/builds/slave/test', 10:53:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:53:00 INFO - 'SHELL': '/bin/bash', 10:53:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DyQ0j1EWqH/Listeners', 10:53:00 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 10:53:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:53:00 INFO - 'USER': 'cltbld', 10:53:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:53:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:53:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:53:00 INFO - 'XPC_FLAGS': '0x0', 10:53:00 INFO - 'XPC_SERVICE_NAME': '0', 10:53:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:53:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:53:00 INFO - Downloading/unpacking blobuploader==1.2.4 10:53: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 10:53: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 10:53: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 10:53: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 10:53: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 10:53: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 10:53:03 INFO - Downloading blobuploader-1.2.4.tar.gz 10:53:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:53:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:53:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:53:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:53:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:53:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:53:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:53:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:53:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:53:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:53:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:53:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:53:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:53:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:53:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:53:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:53:04 INFO - Downloading docopt-0.6.1.tar.gz 10:53:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:53:04 INFO - Installing collected packages: blobuploader, requests, docopt 10:53:04 INFO - Running setup.py install for blobuploader 10:53:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:53:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:53:04 INFO - Running setup.py install for requests 10:53:05 INFO - Running setup.py install for docopt 10:53:05 INFO - Successfully installed blobuploader requests docopt 10:53:05 INFO - Cleaning up... 10:53:05 INFO - Return code: 0 10:53:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:53:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:53:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:53:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:53:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:53:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:05 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 0x108c5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108cf3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ac94a52d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bc2d30>, '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 0x108927db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bbab20>, '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.4c9kCLuys8/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.DyQ0j1EWqH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 10:53:05 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 10:53:05 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 10:53:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4c9kCLuys8/Render', 10:53:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:53:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:53:05 INFO - 'HOME': '/Users/cltbld', 10:53:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:53:05 INFO - 'LOGNAME': 'cltbld', 10:53:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:53:05 INFO - 'MOZ_NO_REMOTE': '1', 10:53:05 INFO - 'NO_EM_RESTART': '1', 10:53:05 INFO - 'PAGER': '/bin/cat', 10:53:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:53:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:53:05 INFO - 'PWD': '/builds/slave/test', 10:53:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:53:05 INFO - 'SHELL': '/bin/bash', 10:53:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DyQ0j1EWqH/Listeners', 10:53:05 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 10:53:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:53:05 INFO - 'USER': 'cltbld', 10:53:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:53:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:53:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:53:05 INFO - 'XPC_FLAGS': '0x0', 10:53:05 INFO - 'XPC_SERVICE_NAME': '0', 10:53:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:53:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:53:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:53:05 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-U5bk99-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:53:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:53:05 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-smnXvW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:53:06 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-ek6Pcu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:53:06 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-ynoj5Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:53:06 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-flXFkp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:53:06 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-BpVYl7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:53:06 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-mUD0sR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:53:06 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-Zdxxk2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:53:06 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-owjkN6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:53:06 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-ebEKBS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:53:06 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-X2FjLX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:53:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:53:07 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-StzjFC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:53:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:53:07 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-JPxnn2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:53:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:53:07 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-fj0R9o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:53:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:53:07 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-lensYU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:53:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:53:07 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-gPP7AN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:53:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:53:07 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-nEMcWK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:53:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:53:07 INFO - Running setup.py install for manifestparser 10:53:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:53:07 INFO - Running setup.py install for mozcrash 10:53:07 INFO - Running setup.py install for mozdebug 10:53:08 INFO - Running setup.py install for mozdevice 10:53:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:53:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:53:08 INFO - Running setup.py install for mozfile 10:53:08 INFO - Running setup.py install for mozhttpd 10:53:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:53:08 INFO - Running setup.py install for mozinfo 10:53:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:53:08 INFO - Running setup.py install for mozInstall 10:53:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:53:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:53:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:53:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:53:08 INFO - Running setup.py install for mozleak 10:53:08 INFO - Running setup.py install for mozlog 10:53:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:53:09 INFO - Running setup.py install for moznetwork 10:53:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:53:09 INFO - Running setup.py install for mozprocess 10:53:09 INFO - Running setup.py install for mozprofile 10:53:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:53:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:53:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:53:09 INFO - Running setup.py install for mozrunner 10:53:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:53:09 INFO - Running setup.py install for mozscreenshot 10:53:09 INFO - Running setup.py install for moztest 10:53:10 INFO - Running setup.py install for mozversion 10:53:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:53:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:53:10 INFO - Cleaning up... 10:53:10 INFO - Return code: 0 10:53:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:53:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:53:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:53:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:53:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:53:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:10 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 0x108c5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108cf3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ac94a52d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bc2d30>, '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 0x108927db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bbab20>, '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.4c9kCLuys8/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.DyQ0j1EWqH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 10:53:10 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 10:53:10 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 10:53:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4c9kCLuys8/Render', 10:53:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:53:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:53:10 INFO - 'HOME': '/Users/cltbld', 10:53:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:53:10 INFO - 'LOGNAME': 'cltbld', 10:53:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:53:10 INFO - 'MOZ_NO_REMOTE': '1', 10:53:10 INFO - 'NO_EM_RESTART': '1', 10:53:10 INFO - 'PAGER': '/bin/cat', 10:53:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:53:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:53:10 INFO - 'PWD': '/builds/slave/test', 10:53:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:53:10 INFO - 'SHELL': '/bin/bash', 10:53:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DyQ0j1EWqH/Listeners', 10:53:10 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 10:53:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:53:10 INFO - 'USER': 'cltbld', 10:53:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:53:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:53:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:53:10 INFO - 'XPC_FLAGS': '0x0', 10:53:10 INFO - 'XPC_SERVICE_NAME': '0', 10:53:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:53:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:53:10 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-0_Xkzu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:53:10 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)) 10:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:53:10 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-NNRWgf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:53:10 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)) 10:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:53:10 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-fWJabC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:53:10 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)) 10:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:53:10 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-tRVg9F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:53:10 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)) 10:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:53:10 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-jf7S9e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:53:11 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)) 10:53:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:53:11 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-hG9xgA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:53:11 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)) 10:53:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:53:11 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-qj3Zrr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:53:11 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)) 10:53:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:53:11 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-xogCcW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:53:11 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)) 10:53:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:53:11 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-8HeLtN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:53:11 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)) 10:53:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:53:11 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-Y7fG5y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:53:11 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)) 10:53:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:53:11 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-Ik_Cw2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:53:11 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)) 10:53:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:53:11 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-mqF96l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:53:11 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)) 10:53:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:53:11 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-LgkBy4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:53:11 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)) 10:53:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:53:11 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-nFqo36-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:53:12 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)) 10:53:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:53:12 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-1gb_PR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:53:12 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)) 10:53:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:53:12 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-pnQGvo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:53:12 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)) 10:53:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:53:12 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-dKyD9P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:53:12 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)) 10:53:12 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)) 10:53:12 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)) 10:53:12 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)) 10:53:12 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)) 10:53:12 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)) 10:53:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:53:12 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 10:53:12 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 10:53:12 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 10:53:12 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 10:53:12 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 10:53:12 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 10:53:15 INFO - Downloading blessings-1.6.tar.gz 10:53:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:53:15 INFO - Installing collected packages: blessings 10:53:15 INFO - Running setup.py install for blessings 10:53:15 INFO - Successfully installed blessings 10:53:15 INFO - Cleaning up... 10:53:15 INFO - Return code: 0 10:53:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:53:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:53:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:53:15 INFO - Reading from file tmpfile_stdout 10:53:15 INFO - Current package versions: 10:53:15 INFO - blessings == 1.6 10:53:15 INFO - blobuploader == 1.2.4 10:53:15 INFO - docopt == 0.6.1 10:53:15 INFO - functools32 == 3.2.3-2 10:53:15 INFO - jsonschema == 2.5.1 10:53:15 INFO - manifestparser == 1.1 10:53:15 INFO - mozInstall == 1.12 10:53:15 INFO - mozcrash == 0.17 10:53:15 INFO - mozdebug == 0.1 10:53:15 INFO - mozdevice == 0.48 10:53:15 INFO - mozfile == 1.2 10:53:15 INFO - mozhttpd == 0.7 10:53:15 INFO - mozinfo == 0.9 10:53:15 INFO - mozleak == 0.1 10:53:15 INFO - mozlog == 3.2 10:53:15 INFO - moznetwork == 0.27 10:53:15 INFO - mozprocess == 0.23 10:53:15 INFO - mozprofile == 0.28 10:53:15 INFO - mozrunner == 6.12 10:53:15 INFO - mozscreenshot == 0.1 10:53:15 INFO - mozsystemmonitor == 0.3 10:53:15 INFO - moztest == 0.7 10:53:15 INFO - mozversion == 1.4 10:53:15 INFO - psutil == 3.1.1 10:53:15 INFO - requests == 1.2.3 10:53:15 INFO - wsgiref == 0.1.2 10:53:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:53:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:53:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:53:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:16 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')]} 10:53:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:53:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:16 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 0x108c5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108cf3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ac94a52d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bc2d30>, '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 0x108927db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bbab20>, '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.4c9kCLuys8/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.DyQ0j1EWqH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 10:53:16 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 10:53:16 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 10:53:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4c9kCLuys8/Render', 10:53:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:53:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:53:16 INFO - 'HOME': '/Users/cltbld', 10:53:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:53:16 INFO - 'LOGNAME': 'cltbld', 10:53:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:53:16 INFO - 'MOZ_NO_REMOTE': '1', 10:53:16 INFO - 'NO_EM_RESTART': '1', 10:53:16 INFO - 'PAGER': '/bin/cat', 10:53:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:53:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:53:16 INFO - 'PWD': '/builds/slave/test', 10:53:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:53:16 INFO - 'SHELL': '/bin/bash', 10:53:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DyQ0j1EWqH/Listeners', 10:53:16 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 10:53:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:53:16 INFO - 'USER': 'cltbld', 10:53:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:53:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:53:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:53:16 INFO - 'XPC_FLAGS': '0x0', 10:53:16 INFO - 'XPC_SERVICE_NAME': '0', 10:53:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:53:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:53:16 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)) 10:53:16 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)) 10:53:16 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)) 10:53:16 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)) 10:53:16 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)) 10:53:16 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)) 10:53:16 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)) 10:53:16 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)) 10:53:16 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)) 10:53:16 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)) 10:53:16 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)) 10:53:16 INFO - Cleaning up... 10:53:16 INFO - Return code: 0 10:53:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:53:16 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')]} 10:53:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:53:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:16 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')]} 10:53:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:53:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:53:16 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 0x108c5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108cf3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ac94a52d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bc2d30>, '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 0x108927db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bbab20>, '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.4c9kCLuys8/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.DyQ0j1EWqH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 10:53:16 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 10:53:16 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 10:53:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4c9kCLuys8/Render', 10:53:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:53:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:53:16 INFO - 'HOME': '/Users/cltbld', 10:53:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:53:16 INFO - 'LOGNAME': 'cltbld', 10:53:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:53:16 INFO - 'MOZ_NO_REMOTE': '1', 10:53:16 INFO - 'NO_EM_RESTART': '1', 10:53:16 INFO - 'PAGER': '/bin/cat', 10:53:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:53:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:53:16 INFO - 'PWD': '/builds/slave/test', 10:53:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:53:16 INFO - 'SHELL': '/bin/bash', 10:53:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DyQ0j1EWqH/Listeners', 10:53:16 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 10:53:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:53:16 INFO - 'USER': 'cltbld', 10:53:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:53:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:53:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:53:16 INFO - 'XPC_FLAGS': '0x0', 10:53:16 INFO - 'XPC_SERVICE_NAME': '0', 10:53:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:53:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:53:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:53:16 INFO - Cleaning up... 10:53:16 INFO - Return code: 0 10:53:16 INFO - Running post-action listener: _resource_record_post_action 10:53:16 INFO - Running post-action listener: _start_resource_monitoring 10:53:16 INFO - Starting resource monitoring. 10:53:16 INFO - ##### 10:53:16 INFO - ##### Finished create-virtualenv step (success) 10:53:16 INFO - ##### 10:53:16 INFO - ##### 10:53:16 INFO - ##### Running install step. 10:53:16 INFO - ##### 10:53:16 INFO - Running pre-action listener: _resource_record_pre_action 10:53:16 INFO - Running main action method: install 10:53:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:53:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:53:17 INFO - Reading from file tmpfile_stdout 10:53:17 INFO - Detecting whether we're running mozinstall >=1.0... 10:53:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:53:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:53:17 INFO - Reading from file tmpfile_stdout 10:53:17 INFO - Output received: 10:53:17 INFO - Usage: mozinstall [options] installer 10:53:17 INFO - Options: 10:53:17 INFO - -h, --help show this help message and exit 10:53:17 INFO - -d DEST, --destination=DEST 10:53:17 INFO - Directory to install application into. [default: 10:53:17 INFO - "/builds/slave/test"] 10:53:17 INFO - --app=APP Application being installed. [default: firefox] 10:53:17 INFO - mkdir: /builds/slave/test/build/application 10:53:17 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'] 10:53:17 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 10:53:52 INFO - Reading from file tmpfile_stdout 10:53:52 INFO - Output received: 10:53:52 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:53:52 INFO - Running post-action listener: _resource_record_post_action 10:53:52 INFO - ##### 10:53:52 INFO - ##### Finished install step (success) 10:53:52 INFO - ##### 10:53:52 INFO - ##### 10:53:52 INFO - ##### Running run-tests step. 10:53:52 INFO - ##### 10:53:52 INFO - Running pre-action listener: _resource_record_pre_action 10:53:52 INFO - Running main action method: run_tests 10:53:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:53:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:53:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:53:52 INFO - 2016-07-15 10:53:52.180 screenresolution[1652:10404] starting screenresolution argv=screenresolution get 10:53:52 INFO - 2016-07-15 10:53:52.182 screenresolution[1652:10404] Display 0: 1600x1200x32@60 10:53:52 INFO - 2016-07-15 10:53:52.188 screenresolution[1653:10406] starting screenresolution argv=screenresolution list 10:53:52 INFO - Available Modes on Display 0 10:53:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:53:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:53:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:53:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:53:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:53:52 INFO - Intel Iris: 10:53:52 INFO - Chipset Model: Intel Iris 10:53:52 INFO - Type: GPU 10:53:52 INFO - Bus: Built-In 10:53:52 INFO - VRAM (Dynamic, Max): 1536 MB 10:53:52 INFO - Vendor: Intel (0x8086) 10:53:52 INFO - Device ID: 0x0a2e 10:53:52 INFO - Revision ID: 0x0009 10:53:52 INFO - Displays: 10:53:52 INFO - Display: 10:53:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:53:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:53:52 INFO - Display Serial Number: 1600x1200 60 10:53:52 INFO - Main Display: Yes 10:53:52 INFO - Mirror: Off 10:53:52 INFO - Online: Yes 10:53:52 INFO - Rotation: Supported 10:53:52 INFO - Return code: 0 10:53:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:53:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:53:52 INFO - Python 2.7.3 10:53:52 INFO - Return code: 0 10:53:52 INFO - grabbing minidump binary from tooltool 10:53:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:53:52 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 0x108bc2d30>, '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 0x108927db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108bbab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:53:52 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 10:53:52 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 10:53:52 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 10:53:52 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:53:52 INFO - Return code: 0 10:53:52 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:53:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:53:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:53:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:53:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:53:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0104', '--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 10:53:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0104 --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 10:53:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4c9kCLuys8/Render', 10:53:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:53:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:53:52 INFO - 'HOME': '/Users/cltbld', 10:53:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:53:52 INFO - 'LOGNAME': 'cltbld', 10:53:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:53:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:53:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:53:52 INFO - 'MOZ_NO_REMOTE': '1', 10:53:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:53:52 INFO - 'NO_EM_RESTART': '1', 10:53:52 INFO - 'PAGER': '/bin/cat', 10:53:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:53:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:53:52 INFO - 'PWD': '/builds/slave/test', 10:53:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:53:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:53:52 INFO - 'SHELL': '/bin/bash', 10:53:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DyQ0j1EWqH/Listeners', 10:53:52 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 10:53:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:53:52 INFO - 'USER': 'cltbld', 10:53:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:53:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:53:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:53:52 INFO - 'XPC_FLAGS': '0x0', 10:53:52 INFO - 'XPC_SERVICE_NAME': '0', 10:53:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:53:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0104', '--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 10:53:52 INFO - mozversion application_buildid: 20160713141046 10:53:52 INFO - mozversion application_changeset: 5fd14a66be3191e5628af3cfb0a456dd70f179b8 10:53:52 INFO - mozversion application_display_name: Nightly 10:53:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:53:52 INFO - mozversion application_name: Firefox 10:53:52 INFO - mozversion application_remotingname: firefox 10:53:52 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:53:52 INFO - mozversion application_vendor: Mozilla 10:53:52 INFO - mozversion application_version: 50.0a1 10:53:52 INFO - mozversion platform_buildid: 20160713141046 10:53:52 INFO - mozversion platform_changeset: 5fd14a66be3191e5628af3cfb0a456dd70f179b8 10:53:52 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:53:52 INFO - mozversion platform_version: 50.0a1 10:53:52 INFO - using testdate: 1468605232 10:53:52 INFO - actual date: 1468605232 10:53:52 INFO - starting webserver on 'localhost:49278' 10:53:52 INFO - SUITE-START | Running 2 tests 10:53:52 INFO - TEST-START | dromaeo_css 10:53:52 INFO - operating with platform_type : mac_ 10:53:52 INFO - Initialising browser for dromaeo_css test... 10:53:52 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/tmpoOXYwL/profile http://localhost:49278/getInfo.html) 10:53:55 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 10:53:55 INFO - PROCESS | 1663 | colorDepth:24 10:53:55 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 10:53:55 INFO - PROCESS | 1663 | __metrics 10:53:55 INFO - PROCESS | 1663 | 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" 10:53:55 INFO - PROCESS | 1663 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:53:55 INFO - TEST-INFO | 1663: exit 0 10:53:55 INFO - Browser initialized. 10:53:55 INFO - Running cycle 1/1 for dromaeo_css test... 10:53:55 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/tmpoOXYwL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:54:07 INFO - PROCESS | 1666 | RSS: Main: 243490816 10:54:07 INFO - PROCESS | 1666 | 10:54:59 INFO - PROCESS | 1666 | 1468605299507 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] 10:57:10 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 10:57:11 INFO - PROCESS | 1666 | RSS: Main: 343908352 10:57:11 INFO - PROCESS | 1666 | 11:00:14 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 11:00:15 INFO - PROCESS | 1666 | RSS: Main: 371335168 11:00:15 INFO - PROCESS | 1666 | 11:03:17 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 11:03:17 INFO - PROCESS | 1666 | RSS: Main: 371056640 11:03:17 INFO - PROCESS | 1666 | 11:06:20 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 11:06:20 INFO - PROCESS | 1666 | RSS: Main: 372051968 11:06:20 INFO - PROCESS | 1666 | 11:09:24 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 11:09:24 INFO - PROCESS | 1666 | RSS: Main: 336678912 11:09:24 INFO - PROCESS | 1666 | 11:12:27 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 11:12:28 INFO - PROCESS | 1666 | RSS: Main: 381169664 11:12:28 INFO - PROCESS | 1666 | 11:12:28 INFO - PROCESS | 1666 | __start_tp_report 11:12:28 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 11:12:28 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 11:12:28 INFO - PROCESS | 1666 | |i|pagename|runs| 11:12:28 INFO - PROCESS | 1666 | |0;dojo.html;3345.5818022747153;3372.9712437706503;3377.983110084451;3402.897913062609;3441.845116969736;5552.666839989593;5610.214569960203;5659.151127330902;5678.886422271557;5711.257536520252;5617.887642247163;5677.772889084432;5709.942900570993;5752.827415177541;5774.711264436783;5127.33344665193;5130.692158470503;5204.661696989702;5210.4008039075425;5325.4940780625975;5303.443138470463;5316.441773613773;5340.519353258206;5350.117230656937;5388.976094542044;5139.997700448414;5167.173252279617;5168.01807656544;5178.4562620924635;5246.2917506136755;5164.509371609729;5187.507186817246;5207.869803254911;5292.153255479105;5309.840704778863;5147.562457191143;5201.973990130063;5213.843584692465;5218.530332270114;5249.606279529013;4609.608183304423;5755.798547050875;5820.767169313222;5834.533237341003;5839.094940284221;6071.241094863142;6137.2942111657285;6138.7237574309265;6139.001538246403;6247.531435142337;6231.563790534665;6280.120783090371;6301.779437719658;6320.936790632127;6332.208473940757;5829.621074630136;5830.854228644275;5838.5037271831525;5839.299284085937;5844.88310233793;6226.221722284715;6226.315105338409;6298.275698294755;6298.811035668938;6303.086052522451;6168.444839964335;6174.660393678301;6176.35148309421;6192.287886893065;6197;6225.688715564203;6300.495960323163;6314.210723659543;6325.177726895567;6331;6031.728572214261;6080.635161890301;6143.112737013596;6157.199564056644;6157.9384206158265;6295.307516173218;6303.621782693053;6305.558610897285;6308;6322.589031712924;5014.172661510883;5020.974895125501;5026.371703537058;5029.798808047637;5060.721660308718;4378.737275763465;4403.735775853395;4404.713693609905;4442.622377097983;4470.128324976663;4688.320193571981;5657.2079908811975;5680.091185410314;5690.772369105272;5751.252337196138;6102.267727872683;6289.119523266746;6299.527535434769;6306.9684651576445;6343.27052388972;5582.63712858671;5653.717314134276;5676.545876329884;5684.459976302244;5691.317041954909;5366.892662146735;5434.864128396822;5444.883798821232;5446.564274858081;5456.781728730806;6310.43206111443;6312.084747711648;6313.305536390993;6316.210473690789;6324.209473815773;6278.4035516625845;6287.443026889406;6318.9052164216655;6360.681965901594;6365.777197798055;6218.968905155626;6259.123722678738;6260.624362538262;6298.590591611531;6313.747450102128;6049.818505444844;6201.658908760061;6219.191505104308;6233.376662333731;6243.750249989949;6011.969940150271;6110.43725517484;6277.654728989767;6324.494040476843;6332.873342533216;6032.668203248863;6082.5438092142385;6111.1304201028925;6137.324894261538;6182.474489668429;6137.263528376623;6140.293866205265;6164.321924588381;6173.104899789595;6173.84565385869;5791;5872.0898260769645;6014.097885317237;6017.30460179018;6071.878562428815;6030.1256317832795;6147.754089836357;6152.200213972155;6153.292371377349;6155.045967874987;5660.235868157746;5886.852828679317;5892.351841297368;5904.3210030847;5954.404559544011 11:12:28 INFO - PROCESS | 1666 | |1;ext.html;465.6554149929052;534.1693666348829;535.7107162132448;546.1234718277165;561.5395375791852;29996.25009374767;31557.368852622963;31885.68114318856;40807.79596102015;41985.37021944665;39510.60489395105;40516.39225411621;43599.56400435995;43658;43673.34489982645;9682.903170968288;9871.358361706501;9873.555689993967;9883.308168428213;9889.20886329092;8743.737687869374;8901.510416927098;8906.599203035847;9041.683541076063;9683.806323873518;8941.016488186295;11799.351035693033;11964.982976447012;12017.038636909027;12279.447424865903;11986.880131198664;12042.337671428068;12060.517579296906;12065.3967301635;12097.6370708878;8659.870101948476;11937.820932686016;12034.398280085961;12036.759264814742;12039.518419263219;40163.59836401628;40706.77879663615;40717.982050448976;43244.135117297796;43293.91765205863;3472.73954453417;3485.3203625293077;3526.612072672004;3670.687991520724;3819.751716138442;30711.232219194477;31787.364252715048;32008;32020;32268;3293.9341213175844;3298.6206586242647;3315.8839440619463;3352.4636058230567;3379.678930501598;326654;327016;327351;327400;331379;56841.43158568361;57174.57063573361;57247.71376143176;57486.425135748104;57503.712481437324;59902.10146847801;59993.70003149956;60008.69995650081;60086;60114.39885601176;15937.36250549965;16324;16374.508764737076;16386.26261818221;16390.836091638932;12901.677458063623;12927.095103342675;12928.741425171444;12934.482620695067;12943.093983421259;13499.257540835151;13764.17414955106;15116.395344186329;15228.543143705707;15269.923650381677;6638.601683898982;6766.887231873394;6918.5502942309595;6947.826304342432;6973.12835895513;887.973360799177;984.601236499219;992.6426486464867;998.0455767532752;1027.5376080763538;26850.731492685212;29073.273168170967;29805.701942980726;30541.083767487013;30630.54054189189;912.9680461183826;1080.524569189554;1112.8497652816766;1145.788029214598;1191.9094028963402;15865.762013569805;15942.36230550765;15963.20183990819;16193.919030404773;16204.513864584082;625482;628607;633966;634711;635688;51266.48733512617;51919.48080519222;57673;58471.12293315604;58579;1421.3532842556613;1442.2572375226766;1452.1940323120348;1456.5484699743113;1458.5770126663149;31232;32189.839050804596;32200.677993220706;32968.34063318768;33075.66924330822;47824.760876195396;50476.74761626168;56504.717476414;58316.708416457644;62200.37799621946;602330;616040;656833;663782;665968;25811.354716132246;26097.869510653083;26353.604695929193;26443;26477.735222647527;37155.44266835946;46920.530794691615;47392.05215895732;47992.28011579899;48092.519074808806;39375;39611.60388396079;39731;40261.39607905825;40564.3915341276;39141.80429097836;39251;39534.604653953094;40203.59796401999;40713;37940;38350.42474362831;38626;38751.806240969745;38822;32340;33350.666493335724;34047.14882127867;34206.17296830006;34213.97358079262;24597.631035534123;24870.37824054413;25407.618885716358;25510;26269.080582180297 11:12:28 INFO - PROCESS | 1666 | |2;jquery.html;348.5225241419256;362.10379311204787;382.08871840660026;392.07665946695533;392.90373858404695;15866.68266634668;16706.830521863474;19413.611727765438;19460.735052221597;21584.676229856545;15508.836837237195;18844.246230150813;19059;19072.80927190731;22268.886555672234;5924.851878703045;5976.133460648203;6145.231846019247;6171.13604095427;6212.875742485147;6037.124616930542;6107.9389206108035;6125.356837532062;6141.078838174265;6143.047827586731;5881.970590147043;6093.360197179299;6099.756009759604;6104.847378815521;6235.937640623603;6177.845553861167;6208.782692605782;6230.408111229449;6235.064740288947;6241.469475094623;5807.593468457209;6132.662703551303;6133.325334213233;6136.6011209271255;6146.200993870812;14705.191214483199;26225.868870655715;26574.93700252007;27381.726182738115;27444;2188.715466989297;2248.0895237428867;2311.7457079721385;2404.350825277183;2787.6097346371525;8202.507849529047;10224.437655928921;10287.537060832206;10621.946890265577;10792.5682972681;1918.5970946101195;1967.3802752133033;1971.310041485483;1995.820376166152;2020.4948762809297;118377;122826.77173228332;123122;123197;124056;11553.768924621461;11595.362255075883;11727;11727.602149194052;11867.347295898808;14626.049306795025;14701.117932924059;14728.337224824909;14734.968552201242;14738.778918316018;8576.1423857615;8739;8779.12208779117;8818.823623527494;8883.644654213758;7491.438142139361;8660.30717542595;8766.780830479289;8790.472571645721;8922.241609463268;4496.4154659894;4581.266997280419;4584.977075114603;4637.9536204637525;4641.721496710208;1615.7899473068426;1867.6544839204792;1914.2630087416444;1936.7675878894622;1942.417274817549;731.8566755783921;799.5522507395877;837.609568116923;863.4991704811194;978.4129522286571;19990.50023749389;22156.530695108697;22265.777342226695;22270.220542280942;22287.77712222857;927.972160835176;939.2345238630494;1000.009990109786;1016.5335020963543;1079.0989523747758;8198.549079800548;11233.943830280796;11376;11539.78316841063;11776.823347649792;485411;508874;562878;566083;570669;140199;141540;142084.5791542092;142781;145059.2747036258;876.5704804645678;965.5027660754716;983.8917719050899;1003.3327836988321;1003.9447830369254;18422.631547369245;18682.532936676147;18783.248670053046;18784.812151878305;18985.71521427152;52983.73508132435;54796.72601636966;55157;57225.71387143037;58102.709486452295;65847.67076164589;77856;80017;80646.19353806388;80770;7228.746993855189;7419.814504637211;7472.551646901204;8085.191480851868;8130.2682758549645;7699.499532530368;11846.466908988943;12576.937115314366;13339;13360.131591446525;10990.065844403316;11453.541858325907;11543.36511491876;11544.365059921785;11567;10922.290051146967;10962.397068161008;11218;11405.600803971822;11471.139664525028;10065.043820837009;11435.942820286178;11705.88294117082;11834.644960651194;11862.288262704265;7450.8509829802015;7453.664585093576;7556.924430755622;7611.771646850603;7672.271134242238;10547.578096876347;11196.936291052338;11214.66356009321;11494.597689080842;11664.358460284766 11:12:28 INFO - PROCESS | 1666 | |3;mootools.html;1514.4320879670124;1564.835692252314;1802.7205783103616;1953;1963.2049020146846;8671.393002489829;8982.371234013606;9154.862677059833;9155.542222888855;9201.631934722604;7963.76108716738;8231.547264900442;8582.313414926806;8874.556272186397;8898.288136949044;2402.639604059392;2430.2952143878256;2432.781049705526;2433.8174636902213;2435.147698305597;2382.440126570263;2385.1413491143176;2422.4791669790993;2427.78149966503;2430.7812296893276;2114.6616541353387;2182.6289530779727;2186.923457678982;2187.442202238427;2191.7260342457193;2345.812335013195;2409.8192635552405;2413.553492603865;2419.27421773469;2422.0917156066475;2399.8200134989947;2421.83047186697;2422.7456117107627;2429.757024297574;2430.9878450607634;9095.363324567283;9127.954360228156;9152.267818574499;9157.587908544065;9181.081891810765;5231.921521177185;5458.263134476834;5460.726963651841;5470.152126420371;5480.451954804527;3575.4279315309686;3643.836027378748;3661.7802931824176;3713.9071523211865;3720.6465385788306;944.1974321826392;1045.681067274481;1075.1452595186768;1083;1095.7972775036565;12193.146479746421;12199.329036902967;12222.388880555936;12229.266244025386;12236.632901012983;4611.354410382549;4722.386089808371;4822.156122678517;4895.167821470359;4903.362562866804;10039.69880903574;10128.139108175741;10171.84742228867;10181.185505159718;10222.080012798884;5427.972860135674;5436.7281635918835;5444.754986025559;5449.455054494519;5452.59105567084;4776.641751868624;4958.752062396866;4983.9252411213865;4984.501549844987;4985.725785081855;3236.8705251790134;3996.160806230666;4025.19496100781;4080.7347522410946;4116.794160292034;1299.3178581244922;1302.667819705969;1303.0878385130447;1316.4010375279224;1321.9206847589176;825.3892119831281;892.2727976698969;892.9866052009226;936.1527817325332;986.5166068626321;5457;6152.507799376039;6155.0151975683675;6235.844103897348;6269.216347956505;911.2800887299092;920.2759830900476;930.2093220762298;984.6356847966298;1007.773251018515;4066.2274167908595;4069;4071.0026043619505;4136;4137.524184718735;10706.1435085193;10927.672169834917;10954.616588419527;11049.447527623586;11067.944660276647;10601.893981060244;11002.724931876766;11003.834942475869;11009.064229540418;11057.612983545538;8348.749537513884;8404.411691181558;8501;8518.361122915807;8531.10423405546;5910.024845900378;5923.282427528988;5940.316863560746;6102.1762062122825;6159.168512250789;9704.854427183456;10236.33463824849;10325.690229293134;10338.328008679411;10343;10236.181105511392;10414;10430.217733669853;10446.373217606968;10483.528241229316;7445.553266804009;7566.697332106654;7688.769336919901;7756.340711039399;7759.650815713419;8538.060813310654;8593.398462107592;8671.046184919527;8689.261412779984;8695.000074991203;5835.620684655484;7527;7557.697692092254;7666.501677390952;7789.065312162576;6475.676216189266;6654.800355989328;7332.890006650011;7527.548347099191;7622.58075805836;6828.146481689789;6874.690638921361;6954.478414119062;6955.095837541087;6959.478039147186;5041.924371134509;5045.798168073383;5052.292679025013;5070.949290507196;5207.1147904857025;5035.7985680572365;5057.31726216956;5089.821856235162;5278.155495120763;5287.233351163983 11:12:28 INFO - PROCESS | 1666 | |4;prototype.html;304.0574219918252;308.2986206380484;319.08900090242366;323.3436124666924;323.705428060465;5410.6753594784295;5456.072467680494;5456.781728730846;5475.342958844934;5506.284183056207;5064;5088.4402715701335;5113.744312784364;5319.9202011969755;5448.548153330997;4110.116324990141;4116.053307739222;4153.293940030187;4157.272477316457;4201.159768046396;3920.137569734654;4175.185838761443;4186.183694179621;4213.031002869342;4229.492460904696;3762.887113386589;3882.9611703883024;4101.753894766309;4121.546629870727;4186.099988502471;3512.7541072124964;3924.9018774530578;4126.66986641068;4132.483439570054;4145.336746120622;3415.8121303328307;3453.913652158691;3581.552305961755;3620;3624.3657359961903;4690.967987042845;4854.271859221145;4910.435299940516;5259.263703081533;5326.893462130735;3874.767513949144;3879.72841901067;4284.3145096784365;4710;4720.48944337812;5693.405846363025;5705.71471426427;5721.656700597978;5732.792874743212;5782.063008892026;3767;3916.5900275900653;3916.6475017248586;3920;3927.6056999764955;5956.702164891738;5993.580449368546;6004.369541198155;6019.63882167067;6024.698765061773;3961.861334853324;3964.6630036447227;3987.142764305688;3998.5001874765653;4013.6588389986596;3803.410471376941;3867.1298957734284;4028.8388464461677;4042.777647229431;4063.349864021742;4645.488996210414;4651.790669419885;4659.883502912454;4705;4772.618190544748;4751.809927602857;4780.5858041688725;5774.220480235114;5800.158976948319;5813.680247586423;1028.7016765138176;1200.9399530023463;1340.9262490563112;1376.2980879751399;1386.6741315790782;711.2887112887113;812.8211793405442;859.7807559072396;914.5884352041475;922.7370199493113;4606.032733126014;5161.5870730341485;5232.110541208004;5238.659487133325;5265.921011184835;4849.563539281482;5636;5706.17402431218;5779.653220806765;5805.854853628609;4504.639628829751;4720.678210101177;5005.624578156653;5006.974965125151;5132.461091585405;3032.3328867649084;3037.8936737214535;3055.9388812223633;3065.6167979002626;3081.768867334959;5285;5320.52115309624;5920.31916329619;6108.908366374508;6151.200343955346;4970.850874473772;5034.546890779774;5307.946920530822;5766.654000759968;5893.02765043763;5530.972345138329;6009.669468179292;6029.698515074317;6071.908921366183;6167.444929956326;5563.805266815642;5855.824325270249;5868.618539794899;5872.207252021093;5905.763769449346;5153.587712982878;5472.878059997612;5727.7422515986045;5807.332156801853;5811.709414529341;5547.694876781816;5923.822285331447;6060.272767267779;6088.4824789893355;6135.478484328882;3299.5050742388835;3705.0918776829417;3932.410138479251;3942.3889297158985;3957.5053118360206;4750;4750.052478483805;5620.887582248414;5700.401457846866;5817.534597232296;5469.827500049882;5625.171819158872;5642.279104873053;5675.205471234113;5837;5411.323584551931;5716.685582293014;5794.681292528951;5806.506446952057;5838.357780644211;4630.953690463052;4703.96594196748;5605.720653417797;5648.293963254593;5669.262995810518;5216.9739151304;5746.339170995389;5757.869588252373;5813.651180929158;5821.633774668998;4749.168895443353;4996.550310472075;5342.839714808407;5385.111456609616;5559.916601251065 11:12:28 INFO - PROCESS | 1666 | |5;yui.html;681.3527149208252;739.2311995524655;743.0266351080089;747.1706405889465;823.9206639302516;14232.857671423282;15258.618534536643;15320.004199727038;15320.157391343471;15350;14742.926285368558;14902.925485372585;14910.25448727562;14953.551393458214;15020.774688379683;867.6182479708924;875.5184648443362;882.5587206396801;912.333996182787;918.4122161816442;713.0765658472264;899.0335389456328;899.204204279211;904.1049361132498;905.9456432614031;763.0118995900294;925.1303774451993;950.173349186209;951.6621599332207;953.7138858342504;923.4320892651039;923.6536298887917;924.2375040591539;925.2043242811177;925.9953700231489;554.7281831902379;688.370141320691;689.1592257445908;692.1382878316477;708.3624737736027;15460.613484662861;15464.5360639181;15465.304061317267;15493.225338733131;15494.61263468411;13703.725925481534;13983.091099078527;14009.949253806006;14017.299135043208;14080.577582672537;11348;12967.351632418437;12976.545820896317;13227.603171904859;13233.73532529344;3939.98030009851;4597.264437689953;4637.118947399983;4714.693544919569;4824.107540105092;116138.83861161365;120223.99999999913;120420;121114;122120;10700.464976751131;10739.892601074045;10851.240413171161;11163.83254251187;11329.376884271443;20838.37484875456;20861.37415877526;20865.37403877886;20874;21702.565948680938;6726.529142959946;7849.725259615886;7946.205379462123;7954.602269886598;7998.520088794691;4112.609302116294;4143.129942712064;4150.481189851269;4166.166766646683;4320.891977700519;3440.9311813763584;3548.520949671813;3559.3771090059654;3560.3235385276716;3562.4300111981956;652.3281020548842;661.3221448015822;661.9966900165565;674.4402146218627;674.5076094451078;506.96958182509167;601.6570554783731;612.8314713453835;639.5746828359182;650.5868773328971;8843.336749743796;8943.776405589912;8953.552322383855;9100.772480688036;9186.86219706705;521.2233771680169;572.9886749886454;598.9341172471024;601.801405536172;647.6308504152588;3911.4523966644692;4355.2813785725;4945.67853089548;5126.2054381570915;5230.163173892159;158352;160126;166175;166704;170098;133682;140140;141671.58328416583;141757;149561;122096.77903221032;123459.38270308591;126135;127842;128233.71766282404;10174.491275436347;10351.792964140826;10367.170626350025;10381.9480902595;10388.480575971322;55840.44159558515;63230.36769632429;65617.68764624585;65983.67008164928;67354.6013128752;86626;99436.75579305126;99838.00161998287;100498.49252261368;101073.9892601094;11311.886881131084;11850.348230847387;12426.418589826708;12706.68233294138;14288.928555357572;12801.487940482277;15489.767653484982;15661.814277510415;15812.130332831344;15829.050256984618;14462.493812716504;14471.348789304717;14602.269886505845;14771.778423323874;14981.925090374478;14212.857871421153;14258.358373872994;14470.493532726305;14597.854021459649;15043.849561504683;13404.798928016282;13436.059475836597;13746.243956582484;14122.646933826325;14796.112233266038;11441.427928603704;11548.364839933549;11655;11671.533138674358;12117.69705757328;11491.31052136841;11535.711607209887;11734.354610496162;11845;11863 11:12:28 INFO - PROCESS | 1666 | __end_tp_report 11:12:28 INFO - PROCESS | 1666 | __start_cc_report 11:12:28 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,1036 11:12:28 INFO - PROCESS | 1666 | __end_cc_report 11:12:28 INFO - PROCESS | 1666 | __startTimestamp1468606348204__endTimestamp 11:12:28 INFO - PROCESS | 1666 | 11:12:28 INFO - PROCESS | 1666 | ------- Summary: start ------- 11:12:28 INFO - PROCESS | 1666 | Number of tests: 6 11:12:28 INFO - PROCESS | 1666 | 11:12:28 INFO - PROCESS | 1666 | [#0] dojo.html Cycles:165 Average:5749.49 Median:6011.97 stddev:645.76 (10.7%) stddev-sans-first:619.59 11:12:28 INFO - PROCESS | 1666 | Values: 3345.6 3373.0 3378.0 3402.9 3441.8 5552.7 5610.2 5659.2 5678.9 5711.3 5617.9 5677.8 5709.9 5752.8 5774.7 5127.3 5130.7 5204.7 5210.4 5325.5 5303.4 5316.4 5340.5 5350.1 5389.0 5140.0 5167.2 5168.0 5178.5 5246.3 5164.5 5187.5 5207.9 5292.2 5309.8 5147.6 5202.0 5213.8 5218.5 5249.6 4609.6 5755.8 5820.8 5834.5 5839.1 6071.2 6137.3 6138.7 6139.0 6247.5 6231.6 6280.1 6301.8 6320.9 6332.2 5829.6 5830.9 5838.5 5839.3 5844.9 6226.2 6226.3 6298.3 6298.8 6303.1 6168.4 6174.7 6176.4 6192.3 6197.0 6225.7 6300.5 6314.2 6325.2 6331.0 6031.7 6080.6 6143.1 6157.2 6157.9 6295.3 6303.6 6305.6 6308.0 6322.6 5014.2 5021.0 5026.4 5029.8 5060.7 4378.7 4403.7 4404.7 4442.6 4470.1 4688.3 5657.2 5680.1 5690.8 5751.3 6102.3 6289.1 6299.5 6307.0 6343.3 5582.6 5653.7 5676.5 5684.5 5691.3 5366.9 5434.9 5444.9 5446.6 5456.8 6310.4 6312.1 6313.3 6316.2 6324.2 6278.4 6287.4 6318.9 6360.7 6365.8 6219.0 6259.1 6260.6 6298.6 6313.7 6049.8 6201.7 6219.2 6233.4 6243.8 6012.0 6110.4 6277.7 6324.5 6332.9 6032.7 6082.5 6111.1 6137.3 6182.5 6137.3 6140.3 6164.3 6173.1 6173.8 5791.0 5872.1 6014.1 6017.3 6071.9 6030.1 6147.8 6152.2 6153.3 6155.0 5660.2 5886.9 5892.4 5904.3 5954.4 11:12:28 INFO - PROCESS | 1666 | 11:12:28 INFO - PROCESS | 1666 | [#1] ext.html Cycles:180 Average:67258.39 Median:37547.72 stddev:148252.92 (394.8%) stddev-sans-first:148583.99 11:12:28 INFO - PROCESS | 1666 | Values: 465.7 534.2 535.7 546.1 561.5 29996.3 31557.4 31885.7 40807.8 41985.4 39510.6 40516.4 43599.6 43658.0 43673.3 9682.9 9871.4 9873.6 9883.3 9889.2 8743.7 8901.5 8906.6 9041.7 9683.8 8941.0 11799.4 11965.0 12017.0 12279.4 11986.9 12042.3 12060.5 12065.4 12097.6 8659.9 11937.8 12034.4 12036.8 12039.5 40163.6 40706.8 40718.0 43244.1 43293.9 3472.7 3485.3 3526.6 3670.7 3819.8 30711.2 31787.4 32008.0 32020.0 32268.0 3293.9 3298.6 3315.9 3352.5 3379.7 326654.0 327016.0 327351.0 327400.0 331379.0 56841.4 57174.6 57247.7 57486.4 57503.7 59902.1 59993.7 60008.7 60086.0 60114.4 15937.4 16324.0 16374.5 16386.3 16390.8 12901.7 12927.1 12928.7 12934.5 12943.1 13499.3 13764.2 15116.4 15228.5 15269.9 6638.6 6766.9 6918.6 6947.8 6973.1 888.0 984.6 992.6 998.0 1027.5 26850.7 29073.3 29805.7 30541.1 30630.5 913.0 1080.5 1112.8 1145.8 1191.9 15865.8 15942.4 15963.2 16193.9 16204.5 625482.0 628607.0 633966.0 634711.0 635688.0 51266.5 51919.5 57673.0 58471.1 58579.0 1421.4 1442.3 1452.2 1456.5 1458.6 31232.0 32189.8 32200.7 32968.3 33075.7 47824.8 50476.7 56504.7 58316.7 62200.4 602330.0 616040.0 656833.0 663782.0 665968.0 25811.4 26097.9 26353.6 26443.0 26477.7 37155.4 46920.5 47392.1 47992.3 48092.5 39375.0 39611.6 39731.0 40261.4 40564.4 39141.8 39251.0 39534.6 40203.6 40713.0 37940.0 38350.4 38626.0 38751.8 38822.0 32340.0 33350.7 34047.1 34206.2 34214.0 24597.6 24870.4 25407.6 25510.0 26269.1 11:12:28 INFO - PROCESS | 1666 | 11:12:28 INFO - PROCESS | 1666 | [#2] jquery.html Cycles:180 Average:33887.57 Median:2358.05 stddev:91287.62 (3871.3%) stddev-sans-first:91508.97 11:12:28 INFO - PROCESS | 1666 | Values: 348.5 362.1 382.1 392.1 392.9 15866.7 16706.8 19413.6 19460.7 21584.7 15508.8 18844.2 19059.0 19072.8 22268.9 5924.9 5976.1 6145.2 6171.1 6212.9 6037.1 6107.9 6125.4 6141.1 6143.0 5882.0 6093.4 6099.8 6104.8 6235.9 6177.8 6208.8 6230.4 6235.1 6241.5 5807.6 6132.7 6133.3 6136.6 6146.2 14705.2 26225.9 26574.9 27381.7 27444.0 2188.7 2248.1 2311.7 2404.4 2787.6 8202.5 10224.4 10287.5 10621.9 10792.6 1918.6 1967.4 1971.3 1995.8 2020.5 118377.0 122826.8 123122.0 123197.0 124056.0 11553.8 11595.4 11727.0 11727.6 11867.3 14626.0 14701.1 14728.3 14735.0 14738.8 8576.1 8739.0 8779.1 8818.8 8883.6 7491.4 8660.3 8766.8 8790.5 8922.2 4496.4 4581.3 4585.0 4638.0 4641.7 1615.8 1867.7 1914.3 1936.8 1942.4 731.9 799.6 837.6 863.5 978.4 19990.5 22156.5 22265.8 22270.2 22287.8 928.0 939.2 1000.0 1016.5 1079.1 8198.5 11233.9 11376.0 11539.8 11776.8 485411.0 508874.0 562878.0 566083.0 570669.0 140199.0 141540.0 142084.6 142781.0 145059.3 876.6 965.5 983.9 1003.3 1003.9 18422.6 18682.5 18783.2 18784.8 18985.7 52983.7 54796.7 55157.0 57225.7 58102.7 65847.7 77856.0 80017.0 80646.2 80770.0 7228.7 7419.8 7472.6 8085.2 8130.3 7699.5 11846.5 12576.9 13339.0 13360.1 10990.1 11453.5 11543.4 11544.4 11567.0 10922.3 10962.4 11218.0 11405.6 11471.1 10065.0 11435.9 11705.9 11834.6 11862.3 7450.9 7453.7 7556.9 7611.8 7672.3 10547.6 11196.9 11214.7 11494.6 11664.4 11:12:28 INFO - PROCESS | 1666 | 11:12:28 INFO - PROCESS | 1666 | [#3] mootools.html Cycles:180 Average:5830.98 Median:4971.34 stddev:3288.02 (66.1%) stddev-sans-first:3281.24 11:12:28 INFO - PROCESS | 1666 | Values: 1514.4 1564.8 1802.7 1953.0 1963.2 8671.4 8982.4 9154.9 9155.5 9201.6 7963.8 8231.5 8582.3 8874.6 8898.3 2402.6 2430.3 2432.8 2433.8 2435.1 2382.4 2385.1 2422.5 2427.8 2430.8 2114.7 2182.6 2186.9 2187.4 2191.7 2345.8 2409.8 2413.6 2419.3 2422.1 2399.8 2421.8 2422.7 2429.8 2431.0 9095.4 9128.0 9152.3 9157.6 9181.1 5231.9 5458.3 5460.7 5470.2 5480.5 3575.4 3643.8 3661.8 3713.9 3720.6 944.2 1045.7 1075.1 1083.0 1095.8 12193.1 12199.3 12222.4 12229.3 12236.6 4611.4 4722.4 4822.2 4895.2 4903.4 10039.7 10128.1 10171.8 10181.2 10222.1 5428.0 5436.7 5444.8 5449.5 5452.6 4776.6 4958.8 4983.9 4984.5 4985.7 3236.9 3996.2 4025.2 4080.7 4116.8 1299.3 1302.7 1303.1 1316.4 1321.9 825.4 892.3 893.0 936.2 986.5 5457.0 6152.5 6155.0 6235.8 6269.2 911.3 920.3 930.2 984.6 1007.8 4066.2 4069.0 4071.0 4136.0 4137.5 10706.1 10927.7 10954.6 11049.4 11067.9 10601.9 11002.7 11003.8 11009.1 11057.6 8348.7 8404.4 8501.0 8518.4 8531.1 5910.0 5923.3 5940.3 6102.2 6159.2 9704.9 10236.3 10325.7 10338.3 10343.0 10236.2 10414.0 10430.2 10446.4 10483.5 7445.6 7566.7 7688.8 7756.3 7759.7 8538.1 8593.4 8671.0 8689.3 8695.0 5835.6 7527.0 7557.7 7666.5 7789.1 6475.7 6654.8 7332.9 7527.5 7622.6 6828.1 6874.7 6954.5 6955.1 6959.5 5041.9 5045.8 5052.3 5070.9 5207.1 5035.8 5057.3 5089.8 5278.2 5287.2 11:12:28 INFO - PROCESS | 1666 | 11:12:28 INFO - PROCESS | 1666 | [#4] prototype.html Cycles:180 Average:4596.88 Median:5101.09 stddev:1414.59 (27.7%) stddev-sans-first:1381.37 11:12:28 INFO - PROCESS | 1666 | Values: 304.1 308.3 319.1 323.3 323.7 5410.7 5456.1 5456.8 5475.3 5506.3 5064.0 5088.4 5113.7 5319.9 5448.5 4110.1 4116.1 4153.3 4157.3 4201.2 3920.1 4175.2 4186.2 4213.0 4229.5 3762.9 3883.0 4101.8 4121.5 4186.1 3512.8 3924.9 4126.7 4132.5 4145.3 3415.8 3453.9 3581.6 3620.0 3624.4 4691.0 4854.3 4910.4 5259.3 5326.9 3874.8 3879.7 4284.3 4710.0 4720.5 5693.4 5705.7 5721.7 5732.8 5782.1 3767.0 3916.6 3916.6 3920.0 3927.6 5956.7 5993.6 6004.4 6019.6 6024.7 3961.9 3964.7 3987.1 3998.5 4013.7 3803.4 3867.1 4028.8 4042.8 4063.3 4645.5 4651.8 4659.9 4705.0 4772.6 4751.8 4780.6 5774.2 5800.2 5813.7 1028.7 1200.9 1340.9 1376.3 1386.7 711.3 812.8 859.8 914.6 922.7 4606.0 5161.6 5232.1 5238.7 5265.9 4849.6 5636.0 5706.2 5779.7 5805.9 4504.6 4720.7 5005.6 5007.0 5132.5 3032.3 3037.9 3055.9 3065.6 3081.8 5285.0 5320.5 5920.3 6108.9 6151.2 4970.9 5034.5 5307.9 5766.7 5893.0 5531.0 6009.7 6029.7 6071.9 6167.4 5563.8 5855.8 5868.6 5872.2 5905.8 5153.6 5472.9 5727.7 5807.3 5811.7 5547.7 5923.8 6060.3 6088.5 6135.5 3299.5 3705.1 3932.4 3942.4 3957.5 4750.0 4750.1 5620.9 5700.4 5817.5 5469.8 5625.2 5642.3 5675.2 5837.0 5411.3 5716.7 5794.7 5806.5 5838.4 4631.0 4704.0 5605.7 5648.3 5669.3 5217.0 5746.3 5757.9 5813.7 5821.6 4749.2 4996.6 5342.8 5385.1 5559.9 11:12:28 INFO - PROCESS | 1666 | 11:12:28 INFO - PROCESS | 1666 | [#5] yui.html Cycles:180 Average:26706.56 Median:168401.00 stddev:43688.59 (25.9%) stddev-sans-first:43767.45 11:12:28 INFO - PROCESS | 1666 | Values: 681.4 739.2 743.0 747.2 823.9 14232.9 15258.6 15320.0 15320.2 15350.0 14742.9 14902.9 14910.3 14953.6 15020.8 867.6 875.5 882.6 912.3 918.4 713.1 899.0 899.2 904.1 905.9 763.0 925.1 950.2 951.7 953.7 923.4 923.7 924.2 925.2 926.0 554.7 688.4 689.2 692.1 708.4 15460.6 15464.5 15465.3 15493.2 15494.6 13703.7 13983.1 14009.9 14017.3 14080.6 11348.0 12967.4 12976.5 13227.6 13233.7 3940.0 4597.3 4637.1 4714.7 4824.1 116138.8 120224.0 120420.0 121114.0 122120.0 10700.5 10739.9 10851.2 11163.8 11329.4 20838.4 20861.4 20865.4 20874.0 21702.6 6726.5 7849.7 7946.2 7954.6 7998.5 4112.6 4143.1 4150.5 4166.2 4320.9 3440.9 3548.5 3559.4 3560.3 3562.4 652.3 661.3 662.0 674.4 674.5 507.0 601.7 612.8 639.6 650.6 8843.3 8943.8 8953.6 9100.8 9186.9 521.2 573.0 598.9 601.8 647.6 3911.5 4355.3 4945.7 5126.2 5230.2 158352.0 160126.0 166175.0 166704.0 170098.0 133682.0 140140.0 141671.6 141757.0 149561.0 122096.8 123459.4 126135.0 127842.0 128233.7 10174.5 10351.8 10367.2 10381.9 10388.5 55840.4 63230.4 65617.7 65983.7 67354.6 86626.0 99436.8 99838.0 100498.5 101074.0 11311.9 11850.3 12426.4 12706.7 14288.9 12801.5 15489.8 15661.8 15812.1 15829.1 14462.5 14471.3 14602.3 14771.8 14981.9 14212.9 14258.4 14470.5 14597.9 15043.8 13404.8 13436.1 13746.2 14122.6 14796.1 11441.4 11548.4 11655.0 11671.5 12117.7 11491.3 11535.7 11734.4 11845.0 11863.0 11:12:28 INFO - PROCESS | 1666 | -------- Summary: end -------- 11:12:28 INFO - PROCESS | 1666 | 11:12:28 INFO - TEST-INFO | 1666: exit 0 11:12:28 INFO - TEST-OK | dromaeo_css | took 1116065ms 11:12:28 INFO - TEST-START | kraken 11:12:28 INFO - operating with platform_type : mac_ 11:12:28 INFO - Initialising browser for kraken test... 11:12:28 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/tmpp_me9_/profile http://localhost:49278/getInfo.html) 11:12:31 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 11:12:31 INFO - PROCESS | 1672 | colorDepth:24 11:12:31 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 11:12:31 INFO - PROCESS | 1672 | __metrics 11:12:31 INFO - PROCESS | 1672 | 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" 11:12:31 INFO - PROCESS | 1672 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:12:31 INFO - TEST-INFO | 1672: exit 0 11:12:31 INFO - Browser initialized. 11:12:31 INFO - Running cycle 1/1 for kraken test... 11:12:31 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/tmpp_me9_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:12:43 INFO - PROCESS | 1673 | RSS: Main: 243580928 11:12:43 INFO - PROCESS | 1673 | 11:12:46 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 11:12:47 INFO - PROCESS | 1673 | RSS: Main: 294326272 11:12:47 INFO - PROCESS | 1673 | 11:12:49 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 11:12:50 INFO - PROCESS | 1673 | RSS: Main: 304214016 11:12:50 INFO - PROCESS | 1673 | 11:12:54 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 11:12:54 INFO - PROCESS | 1673 | RSS: Main: 390418432 11:12:54 INFO - PROCESS | 1673 | 11:12:56 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 11:12:57 INFO - PROCESS | 1673 | RSS: Main: 290742272 11:12:57 INFO - PROCESS | 1673 | 11:12:59 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:12:59 INFO - PROCESS | 1673 | RSS: Main: 294572032 11:12:59 INFO - PROCESS | 1673 | 11:13:02 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 11:13:03 INFO - PROCESS | 1673 | RSS: Main: 331210752 11:13:03 INFO - PROCESS | 1673 | 11:13:06 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 11:13:06 INFO - PROCESS | 1673 | RSS: Main: 331390976 11:13:06 INFO - PROCESS | 1673 | 11:13:09 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 11:13:10 INFO - PROCESS | 1673 | RSS: Main: 330145792 11:13:10 INFO - PROCESS | 1673 | 11:13:11 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:13:12 INFO - PROCESS | 1673 | RSS: Main: 311148544 11:13:12 INFO - PROCESS | 1673 | 11:13:14 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:13:15 INFO - PROCESS | 1673 | RSS: Main: 317304832 11:13:15 INFO - PROCESS | 1673 | 11:13:17 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:13:17 INFO - PROCESS | 1673 | RSS: Main: 315834368 11:13:17 INFO - PROCESS | 1673 | 11:13:20 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:13:21 INFO - PROCESS | 1673 | RSS: Main: 325754880 11:13:21 INFO - PROCESS | 1673 | 11:13:24 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:13:24 INFO - PROCESS | 1673 | RSS: Main: 328036352 11:13:24 INFO - PROCESS | 1673 | 11:13:26 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 11:13:26 INFO - PROCESS | 1673 | RSS: Main: 371544064 11:13:26 INFO - PROCESS | 1673 | 11:13:26 INFO - PROCESS | 1673 | __start_tp_report 11:13:26 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 11:13:26 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 11:13:26 INFO - PROCESS | 1673 | |i|pagename|runs| 11:13:26 INFO - PROCESS | 1673 | |0;ai-astar;102;114;114;113;114;113;114;113;113;112 11:13:26 INFO - PROCESS | 1673 | |1;audio-beat-detection;143;108;105;156;106;105;126;106;137;106 11:13:26 INFO - PROCESS | 1673 | |2;audio-dft;184;186;182;184;184;184;182;185;185;184 11:13:26 INFO - PROCESS | 1673 | |3;audio-fft;78;73;57;107;57;58;60;63;97;57 11:13:26 INFO - PROCESS | 1673 | |4;audio-oscillator;86;85;91;71;71;68;98;70;67;105 11:13:26 INFO - PROCESS | 1673 | |5;imaging-gaussian-blur;89;87;90;87;89;85;128;87;90;87 11:13:26 INFO - PROCESS | 1673 | |6;imaging-darkroom;95;95;96;94;95;94;95;95;95;94 11:13:26 INFO - PROCESS | 1673 | |7;imaging-desaturate;86;84;83;84;85;87;85;86;82;84 11:13:26 INFO - PROCESS | 1673 | |8;json-parse-financial;55;56;54;54;53;55;54;55;52;55 11:13:26 INFO - PROCESS | 1673 | |9;json-stringify-tinderbox;65;72;98;119;113;73;70;69;68;71 11:13:26 INFO - PROCESS | 1673 | |10;stanford-crypto-aes;84;81;82;82;83;83;82;83;85;83 11:13:26 INFO - PROCESS | 1673 | |11;stanford-crypto-ccm;152;147;147;117;144;147;144;140;237;130 11:13:26 INFO - PROCESS | 1673 | |12;stanford-crypto-pbkdf2;164;164;160;164;162;161;160;163;163;162 11:13:26 INFO - PROCESS | 1673 | |13;stanford-crypto-sha256-iterative;67;65;68;64;84;65;67;69;82;83 11:13:26 INFO - PROCESS | 1673 | __end_tp_report 11:13:26 INFO - PROCESS | 1673 | __start_cc_report 11:13:26 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,2050 11:13:26 INFO - PROCESS | 1673 | __end_cc_report 11:13:26 INFO - PROCESS | 1673 | __startTimestamp1468606406932__endTimestamp 11:13:26 INFO - PROCESS | 1673 | 11:13:26 INFO - PROCESS | 1673 | ------- Summary: start ------- 11:13:26 INFO - PROCESS | 1673 | Number of tests: 14 11:13:26 INFO - PROCESS | 1673 | 11:13:26 INFO - PROCESS | 1673 | [#0] ai-astar Cycles:10 Average:112.20 Median:113.50 stddev:3.65 (3.2%) stddev-sans-first:0.71 11:13:26 INFO - PROCESS | 1673 | Values: 102.0 114.0 114.0 113.0 114.0 113.0 114.0 113.0 113.0 112.0 11:13:26 INFO - PROCESS | 1673 | 11:13:26 INFO - PROCESS | 1673 | [#1] audio-beat-detection Cycles:10 Average:119.80 Median:117.00 stddev:19.24 (16.4%) stddev-sans-first:18.48 11:13:26 INFO - PROCESS | 1673 | Values: 143.0 108.0 105.0 156.0 106.0 105.0 126.0 106.0 137.0 106.0 11:13:26 INFO - PROCESS | 1673 | 11:13:26 INFO - PROCESS | 1673 | [#2] audio-dft Cycles:10 Average:184.00 Median:184.00 stddev:1.25 (0.7%) stddev-sans-first:1.32 11:13:26 INFO - PROCESS | 1673 | Values: 184.0 186.0 182.0 184.0 184.0 184.0 182.0 185.0 185.0 184.0 11:13:26 INFO - PROCESS | 1673 | 11:13:26 INFO - PROCESS | 1673 | [#3] audio-fft Cycles:10 Average:70.70 Median:61.50 stddev:18.15 (29.5%) stddev-sans-first:19.06 11:13:26 INFO - PROCESS | 1673 | Values: 78.0 73.0 57.0 107.0 57.0 58.0 60.0 63.0 97.0 57.0 11:13:26 INFO - PROCESS | 1673 | 11:13:26 INFO - PROCESS | 1673 | [#4] audio-oscillator Cycles:10 Average:81.20 Median:78.00 stddev:13.71 (17.6%) stddev-sans-first:14.43 11:13:26 INFO - PROCESS | 1673 | Values: 86.0 85.0 91.0 71.0 71.0 68.0 98.0 70.0 67.0 105.0 11:13:26 INFO - PROCESS | 1673 | 11:13:26 INFO - PROCESS | 1673 | [#5] imaging-gaussian-blur Cycles:10 Average:91.90 Median:88.00 stddev:12.78 (14.5%) stddev-sans-first:13.52 11:13:26 INFO - PROCESS | 1673 | Values: 89.0 87.0 90.0 87.0 89.0 85.0 128.0 87.0 90.0 87.0 11:13:26 INFO - PROCESS | 1673 | 11:13:26 INFO - PROCESS | 1673 | [#6] imaging-darkroom Cycles:10 Average:94.80 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 11:13:26 INFO - PROCESS | 1673 | Values: 95.0 95.0 96.0 94.0 95.0 94.0 95.0 95.0 95.0 94.0 11:13:26 INFO - PROCESS | 1673 | 11:13:26 INFO - PROCESS | 1673 | [#7] imaging-desaturate Cycles:10 Average:84.60 Median:85.00 stddev:1.51 (1.8%) stddev-sans-first:1.51 11:13:26 INFO - PROCESS | 1673 | Values: 86.0 84.0 83.0 84.0 85.0 87.0 85.0 86.0 82.0 84.0 11:13:26 INFO - PROCESS | 1673 | 11:13:26 INFO - PROCESS | 1673 | [#8] json-parse-financial Cycles:10 Average:54.30 Median:55.00 stddev:1.16 (2.1%) stddev-sans-first:1.20 11:13:26 INFO - PROCESS | 1673 | Values: 55.0 56.0 54.0 54.0 53.0 55.0 54.0 55.0 52.0 55.0 11:13:26 INFO - PROCESS | 1673 | 11:13:26 INFO - PROCESS | 1673 | [#9] json-stringify-tinderbox Cycles:10 Average:81.80 Median:70.50 stddev:20.24 (28.7%) stddev-sans-first:20.53 11:13:26 INFO - PROCESS | 1673 | Values: 65.0 72.0 98.0 119.0 113.0 73.0 70.0 69.0 68.0 71.0 11:13:26 INFO - PROCESS | 1673 | 11:13:26 INFO - PROCESS | 1673 | [#10] stanford-crypto-aes Cycles:10 Average:82.80 Median:83.00 stddev:1.14 (1.4%) stddev-sans-first:1.12 11:13:26 INFO - PROCESS | 1673 | Values: 84.0 81.0 82.0 82.0 83.0 83.0 82.0 83.0 85.0 83.0 11:13:26 INFO - PROCESS | 1673 | 11:13:26 INFO - PROCESS | 1673 | [#11] stanford-crypto-ccm Cycles:10 Average:150.50 Median:147.00 stddev:32.07 (21.8%) stddev-sans-first:34.01 11:13:26 INFO - PROCESS | 1673 | Values: 152.0 147.0 147.0 117.0 144.0 147.0 144.0 140.0 237.0 130.0 11:13:26 INFO - PROCESS | 1673 | 11:13:26 INFO - PROCESS | 1673 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.30 Median:163.00 stddev:1.57 (1.0%) stddev-sans-first:1.54 11:13:26 INFO - PROCESS | 1673 | Values: 164.0 164.0 160.0 164.0 162.0 161.0 160.0 163.0 163.0 162.0 11:13:26 INFO - PROCESS | 1673 | 11:13:26 INFO - PROCESS | 1673 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.40 Median:68.50 stddev:8.15 (11.9%) stddev-sans-first:8.49 11:13:26 INFO - PROCESS | 1673 | Values: 67.0 65.0 68.0 64.0 84.0 65.0 67.0 69.0 82.0 83.0 11:13:26 INFO - PROCESS | 1673 | -------- Summary: end -------- 11:13:26 INFO - PROCESS | 1673 | 11:13:27 INFO - TEST-INFO | 1673: exit 0 11:13:27 INFO - TEST-OK | kraken | took 59111ms 11:13:27 INFO - SUITE-END | took 1175s 11:13:28 INFO - Completed test suite (00:19:35) 11:13:28 INFO - javascript benchmark 11:13:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3345.5818022747153, 3372.9712437706503, 3377.983110084451, 3402.897913062609, 3441.845116969736, 5552.666839989593, 5610.214569960203, 5659.151127330902, 5678.886422271557, 5711.257536520252, 5617.887642247163, 5677.772889084432, 5709.942900570993, 5752.827415177541, 5774.711264436783, 5127.33344665193, 5130.692158470503, 5204.661696989702, 5210.4008039075425, 5325.4940780625975, 5303.443138470463, 5316.441773613773, 5340.519353258206, 5350.117230656937, 5388.976094542044, 5139.997700448414, 5167.173252279617, 5168.01807656544, 5178.4562620924635, 5246.2917506136755, 5164.509371609729, 5187.507186817246, 5207.869803254911, 5292.153255479105, 5309.840704778863, 5147.562457191143, 5201.973990130063, 5213.843584692465, 5218.530332270114, 5249.606279529013, 4609.608183304423, 5755.798547050875, 5820.767169313222, 5834.533237341003, 5839.094940284221, 6071.241094863142, 6137.2942111657285, 6138.7237574309265, 6139.001538246403, 6247.531435142337, 6231.563790534665, 6280.120783090371, 6301.779437719658, 6320.936790632127, 6332.208473940757, 5829.621074630136, 5830.854228644275, 5838.5037271831525, 5839.299284085937, 5844.88310233793, 6226.221722284715, 6226.315105338409, 6298.275698294755, 6298.811035668938, 6303.086052522451, 6168.444839964335, 6174.660393678301, 6176.35148309421, 6192.287886893065, 6197.0, 6225.688715564203, 6300.495960323163, 6314.210723659543, 6325.177726895567, 6331.0, 6031.728572214261, 6080.635161890301, 6143.112737013596, 6157.199564056644, 6157.9384206158265, 6295.307516173218, 6303.621782693053, 6305.558610897285, 6308.0, 6322.589031712924, 5014.172661510883, 5020.974895125501, 5026.371703537058, 5029.798808047637, 5060.721660308718, 4378.737275763465, 4403.735775853395, 4404.713693609905, 4442.622377097983, 4470.128324976663, 4688.320193571981, 5657.2079908811975, 5680.091185410314, 5690.772369105272, 5751.252337196138, 6102.267727872683, 6289.119523266746, 6299.527535434769, 6306.9684651576445, 6343.27052388972, 5582.63712858671, 5653.717314134276, 5676.545876329884, 5684.459976302244, 5691.317041954909, 5366.892662146735, 5434.864128396822, 5444.883798821232, 5446.564274858081, 5456.781728730806, 6310.43206111443, 6312.084747711648, 6313.305536390993, 6316.210473690789, 6324.209473815773, 6278.4035516625845, 6287.443026889406, 6318.9052164216655, 6360.681965901594, 6365.777197798055, 6218.968905155626, 6259.123722678738, 6260.624362538262, 6298.590591611531, 6313.747450102128, 6049.818505444844, 6201.658908760061, 6219.191505104308, 6233.376662333731, 6243.750249989949, 6011.969940150271, 6110.43725517484, 6277.654728989767, 6324.494040476843, 6332.873342533216, 6032.668203248863, 6082.5438092142385, 6111.1304201028925, 6137.324894261538, 6182.474489668429, 6137.263528376623, 6140.293866205265, 6164.321924588381, 6173.104899789595, 6173.84565385869, 5791.0, 5872.0898260769645, 6014.097885317237, 6017.30460179018, 6071.878562428815, 6030.1256317832795, 6147.754089836357, 6152.200213972155, 6153.292371377349, 6155.045967874987, 5660.235868157746, 5886.852828679317, 5892.351841297368, 5904.3210030847, 5954.404559544011], "value": 5708.27949275456, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [465.6554149929052, 534.1693666348829, 535.7107162132448, 546.1234718277165, 561.5395375791852, 29996.25009374767, 31557.368852622963, 31885.68114318856, 40807.79596102015, 41985.37021944665, 39510.60489395105, 40516.39225411621, 43599.56400435995, 43658.0, 43673.34489982645, 9682.903170968288, 9871.358361706501, 9873.555689993967, 9883.308168428213, 9889.20886329092, 8743.737687869374, 8901.510416927098, 8906.599203035847, 9041.683541076063, 9683.806323873518, 8941.016488186295, 11799.351035693033, 11964.982976447012, 12017.038636909027, 12279.447424865903, 11986.880131198664, 12042.337671428068, 12060.517579296906, 12065.3967301635, 12097.6370708878, 8659.870101948476, 11937.820932686016, 12034.398280085961, 12036.759264814742, 12039.518419263219, 40163.59836401628, 40706.77879663615, 40717.982050448976, 43244.135117297796, 43293.91765205863, 3472.73954453417, 3485.3203625293077, 3526.612072672004, 3670.687991520724, 3819.751716138442, 30711.232219194477, 31787.364252715048, 32008.0, 32020.0, 32268.0, 3293.9341213175844, 3298.6206586242647, 3315.8839440619463, 3352.4636058230567, 3379.678930501598, 326654.0, 327016.0, 327351.0, 327400.0, 331379.0, 56841.43158568361, 57174.57063573361, 57247.71376143176, 57486.425135748104, 57503.712481437324, 59902.10146847801, 59993.70003149956, 60008.69995650081, 60086.0, 60114.39885601176, 15937.36250549965, 16324.0, 16374.508764737076, 16386.26261818221, 16390.836091638932, 12901.677458063623, 12927.095103342675, 12928.741425171444, 12934.482620695067, 12943.093983421259, 13499.257540835151, 13764.17414955106, 15116.395344186329, 15228.543143705707, 15269.923650381677, 6638.601683898982, 6766.887231873394, 6918.5502942309595, 6947.826304342432, 6973.12835895513, 887.973360799177, 984.601236499219, 992.6426486464867, 998.0455767532752, 1027.5376080763538, 26850.731492685212, 29073.273168170967, 29805.701942980726, 30541.083767487013, 30630.54054189189, 912.9680461183826, 1080.524569189554, 1112.8497652816766, 1145.788029214598, 1191.9094028963402, 15865.762013569805, 15942.36230550765, 15963.20183990819, 16193.919030404773, 16204.513864584082, 625482.0, 628607.0, 633966.0, 634711.0, 635688.0, 51266.48733512617, 51919.48080519222, 57673.0, 58471.12293315604, 58579.0, 1421.3532842556613, 1442.2572375226766, 1452.1940323120348, 1456.5484699743113, 1458.5770126663149, 31232.0, 32189.839050804596, 32200.677993220706, 32968.34063318768, 33075.66924330822, 47824.760876195396, 50476.74761626168, 56504.717476414, 58316.708416457644, 62200.37799621946, 602330.0, 616040.0, 656833.0, 663782.0, 665968.0, 25811.354716132246, 26097.869510653083, 26353.604695929193, 26443.0, 26477.735222647527, 37155.44266835946, 46920.530794691615, 47392.05215895732, 47992.28011579899, 48092.519074808806, 39375.0, 39611.60388396079, 39731.0, 40261.39607905825, 40564.3915341276, 39141.80429097836, 39251.0, 39534.604653953094, 40203.59796401999, 40713.0, 37940.0, 38350.42474362831, 38626.0, 38751.806240969745, 38822.0, 32340.0, 33350.666493335724, 34047.14882127867, 34206.17296830006, 34213.97358079262, 24597.631035534123, 24870.37824054413, 25407.618885716358, 25510.0, 26269.080582180297], "value": 20042.79555691223, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.5225241419256, 362.10379311204787, 382.08871840660026, 392.07665946695533, 392.90373858404695, 15866.68266634668, 16706.830521863474, 19413.611727765438, 19460.735052221597, 21584.676229856545, 15508.836837237195, 18844.246230150813, 19059.0, 19072.80927190731, 22268.886555672234, 5924.851878703045, 5976.133460648203, 6145.231846019247, 6171.13604095427, 6212.875742485147, 6037.124616930542, 6107.9389206108035, 6125.356837532062, 6141.078838174265, 6143.047827586731, 5881.970590147043, 6093.360197179299, 6099.756009759604, 6104.847378815521, 6235.937640623603, 6177.845553861167, 6208.782692605782, 6230.408111229449, 6235.064740288947, 6241.469475094623, 5807.593468457209, 6132.662703551303, 6133.325334213233, 6136.6011209271255, 6146.200993870812, 14705.191214483199, 26225.868870655715, 26574.93700252007, 27381.726182738115, 27444.0, 2188.715466989297, 2248.0895237428867, 2311.7457079721385, 2404.350825277183, 2787.6097346371525, 8202.507849529047, 10224.437655928921, 10287.537060832206, 10621.946890265577, 10792.5682972681, 1918.5970946101195, 1967.3802752133033, 1971.310041485483, 1995.820376166152, 2020.4948762809297, 118377.0, 122826.77173228332, 123122.0, 123197.0, 124056.0, 11553.768924621461, 11595.362255075883, 11727.0, 11727.602149194052, 11867.347295898808, 14626.049306795025, 14701.117932924059, 14728.337224824909, 14734.968552201242, 14738.778918316018, 8576.1423857615, 8739.0, 8779.12208779117, 8818.823623527494, 8883.644654213758, 7491.438142139361, 8660.30717542595, 8766.780830479289, 8790.472571645721, 8922.241609463268, 4496.4154659894, 4581.266997280419, 4584.977075114603, 4637.9536204637525, 4641.721496710208, 1615.7899473068426, 1867.6544839204792, 1914.2630087416444, 1936.7675878894622, 1942.417274817549, 731.8566755783921, 799.5522507395877, 837.609568116923, 863.4991704811194, 978.4129522286571, 19990.50023749389, 22156.530695108697, 22265.777342226695, 22270.220542280942, 22287.77712222857, 927.972160835176, 939.2345238630494, 1000.009990109786, 1016.5335020963543, 1079.0989523747758, 8198.549079800548, 11233.943830280796, 11376.0, 11539.78316841063, 11776.823347649792, 485411.0, 508874.0, 562878.0, 566083.0, 570669.0, 140199.0, 141540.0, 142084.5791542092, 142781.0, 145059.2747036258, 876.5704804645678, 965.5027660754716, 983.8917719050899, 1003.3327836988321, 1003.9447830369254, 18422.631547369245, 18682.532936676147, 18783.248670053046, 18784.812151878305, 18985.71521427152, 52983.73508132435, 54796.72601636966, 55157.0, 57225.71387143037, 58102.709486452295, 65847.67076164589, 77856.0, 80017.0, 80646.19353806388, 80770.0, 7228.746993855189, 7419.814504637211, 7472.551646901204, 8085.191480851868, 8130.2682758549645, 7699.499532530368, 11846.466908988943, 12576.937115314366, 13339.0, 13360.131591446525, 10990.065844403316, 11453.541858325907, 11543.36511491876, 11544.365059921785, 11567.0, 10922.290051146967, 10962.397068161008, 11218.0, 11405.600803971822, 11471.139664525028, 10065.043820837009, 11435.942820286178, 11705.88294117082, 11834.644960651194, 11862.288262704265, 7450.8509829802015, 7453.664585093576, 7556.924430755622, 7611.771646850603, 7672.271134242238, 10547.578096876347, 11196.936291052338, 11214.66356009321, 11494.597689080842, 11664.358460284766], "value": 9599.82533433881, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1514.4320879670124, 1564.835692252314, 1802.7205783103616, 1953.0, 1963.2049020146846, 8671.393002489829, 8982.371234013606, 9154.862677059833, 9155.542222888855, 9201.631934722604, 7963.76108716738, 8231.547264900442, 8582.313414926806, 8874.556272186397, 8898.288136949044, 2402.639604059392, 2430.2952143878256, 2432.781049705526, 2433.8174636902213, 2435.147698305597, 2382.440126570263, 2385.1413491143176, 2422.4791669790993, 2427.78149966503, 2430.7812296893276, 2114.6616541353387, 2182.6289530779727, 2186.923457678982, 2187.442202238427, 2191.7260342457193, 2345.812335013195, 2409.8192635552405, 2413.553492603865, 2419.27421773469, 2422.0917156066475, 2399.8200134989947, 2421.83047186697, 2422.7456117107627, 2429.757024297574, 2430.9878450607634, 9095.363324567283, 9127.954360228156, 9152.267818574499, 9157.587908544065, 9181.081891810765, 5231.921521177185, 5458.263134476834, 5460.726963651841, 5470.152126420371, 5480.451954804527, 3575.4279315309686, 3643.836027378748, 3661.7802931824176, 3713.9071523211865, 3720.6465385788306, 944.1974321826392, 1045.681067274481, 1075.1452595186768, 1083.0, 1095.7972775036565, 12193.146479746421, 12199.329036902967, 12222.388880555936, 12229.266244025386, 12236.632901012983, 4611.354410382549, 4722.386089808371, 4822.156122678517, 4895.167821470359, 4903.362562866804, 10039.69880903574, 10128.139108175741, 10171.84742228867, 10181.185505159718, 10222.080012798884, 5427.972860135674, 5436.7281635918835, 5444.754986025559, 5449.455054494519, 5452.59105567084, 4776.641751868624, 4958.752062396866, 4983.9252411213865, 4984.501549844987, 4985.725785081855, 3236.8705251790134, 3996.160806230666, 4025.19496100781, 4080.7347522410946, 4116.794160292034, 1299.3178581244922, 1302.667819705969, 1303.0878385130447, 1316.4010375279224, 1321.9206847589176, 825.3892119831281, 892.2727976698969, 892.9866052009226, 936.1527817325332, 986.5166068626321, 5457.0, 6152.507799376039, 6155.0151975683675, 6235.844103897348, 6269.216347956505, 911.2800887299092, 920.2759830900476, 930.2093220762298, 984.6356847966298, 1007.773251018515, 4066.2274167908595, 4069.0, 4071.0026043619505, 4136.0, 4137.524184718735, 10706.1435085193, 10927.672169834917, 10954.616588419527, 11049.447527623586, 11067.944660276647, 10601.893981060244, 11002.724931876766, 11003.834942475869, 11009.064229540418, 11057.612983545538, 8348.749537513884, 8404.411691181558, 8501.0, 8518.361122915807, 8531.10423405546, 5910.024845900378, 5923.282427528988, 5940.316863560746, 6102.1762062122825, 6159.168512250789, 9704.854427183456, 10236.33463824849, 10325.690229293134, 10338.328008679411, 10343.0, 10236.181105511392, 10414.0, 10430.217733669853, 10446.373217606968, 10483.528241229316, 7445.553266804009, 7566.697332106654, 7688.769336919901, 7756.340711039399, 7759.650815713419, 8538.060813310654, 8593.398462107592, 8671.046184919527, 8689.261412779984, 8695.000074991203, 5835.620684655484, 7527.0, 7557.697692092254, 7666.501677390952, 7789.065312162576, 6475.676216189266, 6654.800355989328, 7332.890006650011, 7527.548347099191, 7622.58075805836, 6828.146481689789, 6874.690638921361, 6954.478414119062, 6955.095837541087, 6959.478039147186, 5041.924371134509, 5045.798168073383, 5052.292679025013, 5070.949290507196, 5207.1147904857025, 5035.7985680572365, 5057.31726216956, 5089.821856235162, 5278.155495120763, 5287.233351163983], "value": 4677.857397870951, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.0574219918252, 308.2986206380484, 319.08900090242366, 323.3436124666924, 323.705428060465, 5410.6753594784295, 5456.072467680494, 5456.781728730846, 5475.342958844934, 5506.284183056207, 5064.0, 5088.4402715701335, 5113.744312784364, 5319.9202011969755, 5448.548153330997, 4110.116324990141, 4116.053307739222, 4153.293940030187, 4157.272477316457, 4201.159768046396, 3920.137569734654, 4175.185838761443, 4186.183694179621, 4213.031002869342, 4229.492460904696, 3762.887113386589, 3882.9611703883024, 4101.753894766309, 4121.546629870727, 4186.099988502471, 3512.7541072124964, 3924.9018774530578, 4126.66986641068, 4132.483439570054, 4145.336746120622, 3415.8121303328307, 3453.913652158691, 3581.552305961755, 3620.0, 3624.3657359961903, 4690.967987042845, 4854.271859221145, 4910.435299940516, 5259.263703081533, 5326.893462130735, 3874.767513949144, 3879.72841901067, 4284.3145096784365, 4710.0, 4720.48944337812, 5693.405846363025, 5705.71471426427, 5721.656700597978, 5732.792874743212, 5782.063008892026, 3767.0, 3916.5900275900653, 3916.6475017248586, 3920.0, 3927.6056999764955, 5956.702164891738, 5993.580449368546, 6004.369541198155, 6019.63882167067, 6024.698765061773, 3961.861334853324, 3964.6630036447227, 3987.142764305688, 3998.5001874765653, 4013.6588389986596, 3803.410471376941, 3867.1298957734284, 4028.8388464461677, 4042.777647229431, 4063.349864021742, 4645.488996210414, 4651.790669419885, 4659.883502912454, 4705.0, 4772.618190544748, 4751.809927602857, 4780.5858041688725, 5774.220480235114, 5800.158976948319, 5813.680247586423, 1028.7016765138176, 1200.9399530023463, 1340.9262490563112, 1376.2980879751399, 1386.6741315790782, 711.2887112887113, 812.8211793405442, 859.7807559072396, 914.5884352041475, 922.7370199493113, 4606.032733126014, 5161.5870730341485, 5232.110541208004, 5238.659487133325, 5265.921011184835, 4849.563539281482, 5636.0, 5706.17402431218, 5779.653220806765, 5805.854853628609, 4504.639628829751, 4720.678210101177, 5005.624578156653, 5006.974965125151, 5132.461091585405, 3032.3328867649084, 3037.8936737214535, 3055.9388812223633, 3065.6167979002626, 3081.768867334959, 5285.0, 5320.52115309624, 5920.31916329619, 6108.908366374508, 6151.200343955346, 4970.850874473772, 5034.546890779774, 5307.946920530822, 5766.654000759968, 5893.02765043763, 5530.972345138329, 6009.669468179292, 6029.698515074317, 6071.908921366183, 6167.444929956326, 5563.805266815642, 5855.824325270249, 5868.618539794899, 5872.207252021093, 5905.763769449346, 5153.587712982878, 5472.878059997612, 5727.7422515986045, 5807.332156801853, 5811.709414529341, 5547.694876781816, 5923.822285331447, 6060.272767267779, 6088.4824789893355, 6135.478484328882, 3299.5050742388835, 3705.0918776829417, 3932.410138479251, 3942.3889297158985, 3957.5053118360206, 4750.0, 4750.052478483805, 5620.887582248414, 5700.401457846866, 5817.534597232296, 5469.827500049882, 5625.171819158872, 5642.279104873053, 5675.205471234113, 5837.0, 5411.323584551931, 5716.685582293014, 5794.681292528951, 5806.506446952057, 5838.357780644211, 4630.953690463052, 4703.96594196748, 5605.720653417797, 5648.293963254593, 5669.262995810518, 5216.9739151304, 5746.339170995389, 5757.869588252373, 5813.651180929158, 5821.633774668998, 4749.168895443353, 4996.550310472075, 5342.839714808407, 5385.111456609616, 5559.916601251065], "value": 4139.525993320251, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [681.3527149208252, 739.2311995524655, 743.0266351080089, 747.1706405889465, 823.9206639302516, 14232.857671423282, 15258.618534536643, 15320.004199727038, 15320.157391343471, 15350.0, 14742.926285368558, 14902.925485372585, 14910.25448727562, 14953.551393458214, 15020.774688379683, 867.6182479708924, 875.5184648443362, 882.5587206396801, 912.333996182787, 918.4122161816442, 713.0765658472264, 899.0335389456328, 899.204204279211, 904.1049361132498, 905.9456432614031, 763.0118995900294, 925.1303774451993, 950.173349186209, 951.6621599332207, 953.7138858342504, 923.4320892651039, 923.6536298887917, 924.2375040591539, 925.2043242811177, 925.9953700231489, 554.7281831902379, 688.370141320691, 689.1592257445908, 692.1382878316477, 708.3624737736027, 15460.613484662861, 15464.5360639181, 15465.304061317267, 15493.225338733131, 15494.61263468411, 13703.725925481534, 13983.091099078527, 14009.949253806006, 14017.299135043208, 14080.577582672537, 11348.0, 12967.351632418437, 12976.545820896317, 13227.603171904859, 13233.73532529344, 3939.98030009851, 4597.264437689953, 4637.118947399983, 4714.693544919569, 4824.107540105092, 116138.83861161365, 120223.99999999913, 120420.0, 121114.0, 122120.0, 10700.464976751131, 10739.892601074045, 10851.240413171161, 11163.83254251187, 11329.376884271443, 20838.37484875456, 20861.37415877526, 20865.37403877886, 20874.0, 21702.565948680938, 6726.529142959946, 7849.725259615886, 7946.205379462123, 7954.602269886598, 7998.520088794691, 4112.609302116294, 4143.129942712064, 4150.481189851269, 4166.166766646683, 4320.891977700519, 3440.9311813763584, 3548.520949671813, 3559.3771090059654, 3560.3235385276716, 3562.4300111981956, 652.3281020548842, 661.3221448015822, 661.9966900165565, 674.4402146218627, 674.5076094451078, 506.96958182509167, 601.6570554783731, 612.8314713453835, 639.5746828359182, 650.5868773328971, 8843.336749743796, 8943.776405589912, 8953.552322383855, 9100.772480688036, 9186.86219706705, 521.2233771680169, 572.9886749886454, 598.9341172471024, 601.801405536172, 647.6308504152588, 3911.4523966644692, 4355.2813785725, 4945.67853089548, 5126.2054381570915, 5230.163173892159, 158352.0, 160126.0, 166175.0, 166704.0, 170098.0, 133682.0, 140140.0, 141671.58328416583, 141757.0, 149561.0, 122096.77903221032, 123459.38270308591, 126135.0, 127842.0, 128233.71766282404, 10174.491275436347, 10351.792964140826, 10367.170626350025, 10381.9480902595, 10388.480575971322, 55840.44159558515, 63230.36769632429, 65617.68764624585, 65983.67008164928, 67354.6013128752, 86626.0, 99436.75579305126, 99838.00161998287, 100498.49252261368, 101073.9892601094, 11311.886881131084, 11850.348230847387, 12426.418589826708, 12706.68233294138, 14288.928555357572, 12801.487940482277, 15489.767653484982, 15661.814277510415, 15812.130332831344, 15829.050256984618, 14462.493812716504, 14471.348789304717, 14602.269886505845, 14771.778423323874, 14981.925090374478, 14212.857871421153, 14258.358373872994, 14470.493532726305, 14597.854021459649, 15043.849561504683, 13404.798928016282, 13436.059475836597, 13746.243956582484, 14122.646933826325, 14796.112233266038, 11441.427928603704, 11548.364839933549, 11655.0, 11671.533138674358, 12117.69705757328, 11491.31052136841, 11535.711607209887, 11734.354610496162, 11845.0, 11863.0], "value": 8053.109008625968, "unit": "score"}], "name": "dromaeo_css", "value": 7452.274404706511, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 114.0, 114.0, 113.0, 114.0, 113.0, 114.0, 113.0, 113.0, 112.0], "value": 112.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 108.0, 105.0, 156.0, 106.0, 105.0, 126.0, 106.0, 137.0, 106.0], "value": 119.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 186.0, 182.0, 184.0, 184.0, 184.0, 182.0, 185.0, 185.0, 184.0], "value": 184.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 73.0, 57.0, 107.0, 57.0, 58.0, 60.0, 63.0, 97.0, 57.0], "value": 70.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 91.0, 71.0, 71.0, 68.0, 98.0, 70.0, 67.0, 105.0], "value": 81.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 90.0, 87.0, 89.0, 85.0, 128.0, 87.0, 90.0, 87.0], "value": 91.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 94.0, 95.0, 94.0, 95.0, 95.0, 95.0, 94.0], "value": 94.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 83.0, 84.0, 85.0, 87.0, 85.0, 86.0, 82.0, 84.0], "value": 84.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 54.0, 54.0, 53.0, 55.0, 54.0, 55.0, 52.0, 55.0], "value": 54.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 72.0, 98.0, 119.0, 113.0, 73.0, 70.0, 69.0, 68.0, 71.0], "value": 81.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 81.0, 82.0, 82.0, 83.0, 83.0, 82.0, 83.0, 85.0, 83.0], "value": 82.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 147.0, 147.0, 117.0, 144.0, 147.0, 144.0, 140.0, 237.0, 130.0], "value": 150.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 164.0, 160.0, 164.0, 162.0, 161.0, 160.0, 163.0, 163.0, 162.0], "value": 162.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 68.0, 64.0, 84.0, 65.0, 67.0, 69.0, 82.0, 83.0], "value": 71.4, "unit": "score"}], "name": "kraken", "value": 1442.3, "alertThreshold": 2.0}]} 11:13:28 INFO - Return code: 0 11:13:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:13:28 INFO - # TBPL SUCCESS # 11:13:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:13:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:13:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:13:28 INFO - 2016-07-15 11:13:28.202 screenresolution[1677:12590] starting screenresolution argv=screenresolution get 11:13:28 INFO - 2016-07-15 11:13:28.205 screenresolution[1677:12590] Display 0: 1600x1200x32@60 11:13:28 INFO - 2016-07-15 11:13:28.210 screenresolution[1678:12594] starting screenresolution argv=screenresolution list 11:13:28 INFO - Available Modes on Display 0 11:13:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:13:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:13:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:13:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:13:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:13:28 INFO - Intel Iris: 11:13:28 INFO - Chipset Model: Intel Iris 11:13:28 INFO - Type: GPU 11:13:28 INFO - Bus: Built-In 11:13:28 INFO - VRAM (Dynamic, Max): 1536 MB 11:13:28 INFO - Vendor: Intel (0x8086) 11:13:28 INFO - Device ID: 0x0a2e 11:13:28 INFO - Revision ID: 0x0009 11:13:28 INFO - Displays: 11:13:28 INFO - Display: 11:13:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:13:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:13:28 INFO - Display Serial Number: 1600x1200 60 11:13:28 INFO - Main Display: Yes 11:13:28 INFO - Mirror: Off 11:13:28 INFO - Online: Yes 11:13:28 INFO - Rotation: Supported 11:13:28 INFO - Return code: 0 11:13:28 INFO - Running post-action listener: _resource_record_post_action 11:13:28 INFO - ##### 11:13:28 INFO - ##### Finished run-tests step (success) 11:13:28 INFO - ##### 11:13:28 INFO - Running post-run listener: _resource_record_post_run 11:13:28 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 31522304; Write bytes: 708158464; Read time: 311; Write time: 1892 11:13:28 INFO - TinderboxPrint: CPU usage
53.3% 11:13:28 INFO - TinderboxPrint: I/O read bytes / time
31,522,304 / 311 11:13:28 INFO - TinderboxPrint: I/O write bytes / time
708,158,464 / 1,892 11:13:28 INFO - TinderboxPrint: CPU idle
2,264.6 (46.7%) 11:13:28 INFO - TinderboxPrint: CPU system
962.4 (19.9%) 11:13:28 INFO - TinderboxPrint: CPU user
1,618.7 (33.4%) 11:13:28 INFO - TinderboxPrint: Swap in / out
434,114,560 / 0 11:13:28 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 405228032; Write bytes: 401182208; Read time: 30756; Write time: 758 11:13:28 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 29318656; Write bytes: 295261696; Read time: 285; Write time: 1100 11:13:28 INFO - Running post-run listener: _upload_blobber_files 11:13:28 INFO - Blob upload gear active. 11:13:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:13:28 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 11:13:28 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:13:28 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:13:29 INFO - (blobuploader) - INFO - Open directory for files ... 11:13:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:13:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:13:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:13:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:13:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:13:30 INFO - (blobuploader) - INFO - Done attempting. 11:13:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:13:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:13:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:13:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:13:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:13:31 INFO - (blobuploader) - INFO - Done attempting. 11:13:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:13:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:13:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:13:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:13:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:13:33 INFO - (blobuploader) - INFO - Done attempting. 11:13:33 INFO - (blobuploader) - INFO - Iteration through files over. 11:13:33 INFO - Return code: 0 11:13:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:13:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:13:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca9d3f055d5b08a1cc5a8a7158ccece89b47863b949b2c1d3fe9ad24c696d6e6e051a49dfda48edd0d08d319f0ef5ca6b393db13e71b0558aa2e3e211068eaa8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/364a562b0b8c7c68b85ca24e12561113732b74429fab6431f16547137d9c81783bbc305ce9defdbc3cbca3ef0b1d7265789a75efe3bbea52ec87f33d79fac176", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/62ff8374d561326e3b71c4976df095be8fc3b2e915278330faaafd4ed7938218fde2e011b718b2b86a558dd792f63a19aa4ae763473047dc394edabf35365849"} 11:13:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:13:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:13:33 INFO - Contents: 11:13:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca9d3f055d5b08a1cc5a8a7158ccece89b47863b949b2c1d3fe9ad24c696d6e6e051a49dfda48edd0d08d319f0ef5ca6b393db13e71b0558aa2e3e211068eaa8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/364a562b0b8c7c68b85ca24e12561113732b74429fab6431f16547137d9c81783bbc305ce9defdbc3cbca3ef0b1d7265789a75efe3bbea52ec87f33d79fac176", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/62ff8374d561326e3b71c4976df095be8fc3b2e915278330faaafd4ed7938218fde2e011b718b2b86a558dd792f63a19aa4ae763473047dc394edabf35365849"} 11:13:33 INFO - Running post-run listener: copy_logs_to_upload_dir 11:13:33 INFO - Copying logs to upload dir... 11:13:33 INFO - mkdir: /builds/slave/test/build/upload/logs 11:13:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1282.461094 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2016-07-15 11:13:33.710918) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-15 11:13:33.714030) ========= 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.4c9kCLuys8/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.DyQ0j1EWqH/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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/Firefox/sha512/ca9d3f055d5b08a1cc5a8a7158ccece89b47863b949b2c1d3fe9ad24c696d6e6e051a49dfda48edd0d08d319f0ef5ca6b393db13e71b0558aa2e3e211068eaa8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/364a562b0b8c7c68b85ca24e12561113732b74429fab6431f16547137d9c81783bbc305ce9defdbc3cbca3ef0b1d7265789a75efe3bbea52ec87f33d79fac176", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/62ff8374d561326e3b71c4976df095be8fc3b2e915278330faaafd4ed7938218fde2e011b718b2b86a558dd792f63a19aa4ae763473047dc394edabf35365849"} build_url:https://queue.taskcluster.net/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011392 build_url: 'https://queue.taskcluster.net/v1/task/AbB3eD1KQ8qHxsobdL1-xA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca9d3f055d5b08a1cc5a8a7158ccece89b47863b949b2c1d3fe9ad24c696d6e6e051a49dfda48edd0d08d319f0ef5ca6b393db13e71b0558aa2e3e211068eaa8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/364a562b0b8c7c68b85ca24e12561113732b74429fab6431f16547137d9c81783bbc305ce9defdbc3cbca3ef0b1d7265789a75efe3bbea52ec87f33d79fac176", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/62ff8374d561326e3b71c4976df095be8fc3b2e915278330faaafd4ed7938218fde2e011b718b2b86a558dd792f63a19aa4ae763473047dc394edabf35365849"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-15 11:13:33.745866) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 11:13:33.746179) ========= 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.4c9kCLuys8/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.DyQ0j1EWqH/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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.005004 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-15 11:13:33.794462) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-15 11:13:33.794741) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-15 11:13:33.795016) ========= ========= Total master_lag: 0.17 =========