builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0166 starttime: 1467904140.98 results: success (0) buildid: 20160707054022 builduid: 12b31bc1fced433d867d9c8b1d80a40f revision: ec92630e4c635ef1fbaac6115c6a123de1fd5d28 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-07 08:09:00.981396) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-07 08:09:00.982050) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-07 08:09:00.982353) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-07 08:09:01.014324) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-07 08:09:01.014595) ========= 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.9PESWhxtRR/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.gJuvkKr4qo/Listeners TMPDIR=/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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-07 08:09:01-- 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% 13.4M=0.001s 2016-07-07 08:09:01 (13.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.099300 ========= master_lag: 1.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-07 08:09:02.157429) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 08:09:02.157722) ========= 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.9PESWhxtRR/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.gJuvkKr4qo/Listeners TMPDIR=/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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.036798 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 08:09:02.220488) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-07 08:09:02.220940) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev ec92630e4c635ef1fbaac6115c6a123de1fd5d28 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev ec92630e4c635ef1fbaac6115c6a123de1fd5d28 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.9PESWhxtRR/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.gJuvkKr4qo/Listeners TMPDIR=/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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-07 08:09:02,291 truncating revision to first 12 chars 2016-07-07 08:09:02,291 Setting DEBUG logging. 2016-07-07 08:09:02,291 attempt 1/10 2016-07-07 08:09:02,292 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/ec92630e4c63?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-07 08:09:04,657 unpacking tar archive at: fx-team-ec92630e4c63/testing/mozharness/ program finished with exit code 0 elapsedTime=2.708681 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-07 08:09:04.951793) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-07 08:09:04.955177) ========= script_repo_revision: ec92630e4c635ef1fbaac6115c6a123de1fd5d28 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-07 08:09:04.955616) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-07 08:09:04.955906) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-07 08:09:04.975798) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-07-07 08:09:04.976244) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.9PESWhxtRR/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.gJuvkKr4qo/Listeners TMPDIR=/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 08:09:05 INFO - MultiFileLogger online at 20160707 08:09:05 in /builds/slave/test 08:09:05 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 08:09:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:09:05 INFO - {'append_to_log': False, 08:09:05 INFO - 'base_work_dir': '/builds/slave/test', 08:09:05 INFO - 'blob_upload_branch': 'Fx-Team', 08:09:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:09:05 INFO - 'branch': 'Fx-Team', 08:09:05 INFO - 'buildbot_json_path': 'buildprops.json', 08:09:05 INFO - 'config_files': ('talos/mac_config.py',), 08:09:05 INFO - 'default_actions': ('clobber', 08:09:05 INFO - 'read-buildbot-config', 08:09:05 INFO - 'download-and-extract', 08:09:05 INFO - 'populate-webroot', 08:09:05 INFO - 'create-virtualenv', 08:09:05 INFO - 'install', 08:09:05 INFO - 'run-tests'), 08:09:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:09:05 INFO - 'download_minidump_stackwalk': True, 08:09:05 INFO - 'download_symbols': 'ondemand', 08:09:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:09:05 INFO - 'tooltool.py': '/tools/tooltool.py', 08:09:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:09:05 INFO - '/tools/misc-python/virtualenv.py')}, 08:09:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:09:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:09:05 INFO - 'installer_path': 'installer.exe', 08:09:05 INFO - 'log_level': 'info', 08:09:05 INFO - 'log_name': 'talos', 08:09:05 INFO - 'log_to_console': True, 08:09:05 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:09:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:09:05 INFO - 'opt_config_files': (), 08:09:05 INFO - 'pip_index': False, 08:09:05 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:09:05 INFO - 'cmd': ('bash', 08:09:05 INFO - '-c', 08:09:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:09:05 INFO - 'enabled': True, 08:09:05 INFO - 'halt_on_failure': False, 08:09:05 INFO - 'name': 'check_screen_resolution'},), 08:09:05 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:09:05 INFO - 'cmd': ('bash', 08:09:05 INFO - '-c', 08:09:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:09:05 INFO - 'enabled': True, 08:09:05 INFO - 'halt_on_failure': False, 08:09:05 INFO - 'name': 'check_screen_resolution'},), 08:09:05 INFO - 'run_cmd_checks_enabled': True, 08:09:05 INFO - 'sps_profile': False, 08:09:05 INFO - 'sps_profile_interval': 0, 08:09:05 INFO - 'suite': 'dromaeojs', 08:09:05 INFO - 'system_bits': '32', 08:09:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:09:05 INFO - 'title': 't-yosemite-r7-0166', 08:09:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:09:05 INFO - 'use_talos_json': True, 08:09:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:09:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:09:05 INFO - 'work_dir': 'build'} 08:09:05 INFO - ##### 08:09:05 INFO - ##### Running clobber step. 08:09:05 INFO - ##### 08:09:05 INFO - Running pre-action listener: _resource_record_pre_action 08:09:05 INFO - Running main action method: clobber 08:09:05 INFO - rmtree: /builds/slave/test/build 08:09:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:09:07 INFO - Running post-action listener: _resource_record_post_action 08:09:07 INFO - ##### 08:09:07 INFO - ##### Finished clobber step (success) 08:09:07 INFO - ##### 08:09:07 INFO - ##### 08:09:07 INFO - ##### Running read-buildbot-config step. 08:09:07 INFO - ##### 08:09:07 INFO - Running pre-action listener: _resource_record_pre_action 08:09:07 INFO - Running main action method: read_buildbot_config 08:09:07 INFO - Using buildbot properties: 08:09:07 INFO - { 08:09:07 INFO - "project": "", 08:09:07 INFO - "product": "firefox", 08:09:07 INFO - "script_repo_revision": "production", 08:09:07 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 08:09:07 INFO - "repository": "", 08:09:07 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 08:09:07 INFO - "buildid": "20160707054022", 08:09:07 INFO - "pgo_build": "False", 08:09:07 INFO - "basedir": "/builds/slave/test", 08:09:07 INFO - "buildnumber": 874, 08:09:07 INFO - "slavename": "t-yosemite-r7-0166", 08:09:07 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 08:09:07 INFO - "platform": "yosemite_r7", 08:09:07 INFO - "scheduler": "tests-fx-team-macosx64-talos", 08:09:07 INFO - "branch": "fx-team", 08:09:07 INFO - "repo_path": "integration/fx-team", 08:09:07 INFO - "revision": "ec92630e4c635ef1fbaac6115c6a123de1fd5d28", 08:09:07 INFO - "stage_platform": "macosx64", 08:09:07 INFO - "builduid": "12b31bc1fced433d867d9c8b1d80a40f", 08:09:07 INFO - "slavebuilddir": "test" 08:09:07 INFO - } 08:09:07 INFO - Found installer url https://queue.taskcluster.net/v1/task/FqgNp5jFTNu-hqS3bwx7iw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 08:09:07 INFO - Running post-action listener: _resource_record_post_action 08:09:07 INFO - ##### 08:09:07 INFO - ##### Finished read-buildbot-config step (success) 08:09:07 INFO - ##### 08:09:07 INFO - ##### 08:09:07 INFO - ##### Running download-and-extract step. 08:09:07 INFO - ##### 08:09:07 INFO - Running pre-action listener: _resource_record_pre_action 08:09:07 INFO - Running main action method: download_and_extract 08:09:07 INFO - mkdir: /builds/slave/test/build/tests 08:09:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:09:07 INFO - https://queue.taskcluster.net/v1/task/FqgNp5jFTNu-hqS3bwx7iw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 08:09:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FqgNp5jFTNu-hqS3bwx7iw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 08:09:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FqgNp5jFTNu-hqS3bwx7iw/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 08:09:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FqgNp5jFTNu-hqS3bwx7iw/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 08:09:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FqgNp5jFTNu-hqS3bwx7iw/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 08:09:08 INFO - Downloaded 1199 bytes. 08:09:08 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 08:09:08 INFO - Using the following test package requirements: 08:09:08 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 08:09:08 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 08:09:08 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 08:09:08 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 08:09:08 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 08:09:08 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 08:09:08 INFO - u'jsshell-mac.zip'], 08:09:08 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 08:09:08 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 08:09:08 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 08:09:08 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 08:09:08 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 08:09:08 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 08:09:08 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 08:09:08 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 08:09:08 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 08:09:08 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 08:09:08 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 08:09:08 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:09:08 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')]} 08:09:08 INFO - https://queue.taskcluster.net/v1/task/FqgNp5jFTNu-hqS3bwx7iw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:09:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FqgNp5jFTNu-hqS3bwx7iw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 08:09:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FqgNp5jFTNu-hqS3bwx7iw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 08:09:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FqgNp5jFTNu-hqS3bwx7iw/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 08:09:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FqgNp5jFTNu-hqS3bwx7iw/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 08:09:11 INFO - Downloaded 18806307 bytes. 08:09:11 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'] 08:09:11 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 08:09:14 INFO - Return code: 0 08:09:14 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 08:09:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:09:14 INFO - https://queue.taskcluster.net/v1/task/FqgNp5jFTNu-hqS3bwx7iw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:09:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FqgNp5jFTNu-hqS3bwx7iw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 08:09:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FqgNp5jFTNu-hqS3bwx7iw/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 08:09:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FqgNp5jFTNu-hqS3bwx7iw/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 08:09:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FqgNp5jFTNu-hqS3bwx7iw/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 08:09:16 INFO - Downloaded 18806307 bytes. 08:09:16 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'] 08:09:16 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 08:09:20 INFO - Return code: 0 08:09:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:09:20 INFO - https://queue.taskcluster.net/v1/task/FqgNp5jFTNu-hqS3bwx7iw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 08:09:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FqgNp5jFTNu-hqS3bwx7iw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 08:09:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FqgNp5jFTNu-hqS3bwx7iw/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 08:09:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FqgNp5jFTNu-hqS3bwx7iw/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 08:09:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FqgNp5jFTNu-hqS3bwx7iw/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 08:09:24 INFO - Downloaded 14403333 bytes. 08:09:24 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'] 08:09:24 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 08:09:24 INFO - Return code: 0 08:09:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:09:24 INFO - https://queue.taskcluster.net/v1/task/FqgNp5jFTNu-hqS3bwx7iw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 08:09:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FqgNp5jFTNu-hqS3bwx7iw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 08:09:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FqgNp5jFTNu-hqS3bwx7iw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 08:09:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FqgNp5jFTNu-hqS3bwx7iw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 08:09:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FqgNp5jFTNu-hqS3bwx7iw/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 08:09:35 INFO - Downloaded 112424986 bytes. 08:09:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FqgNp5jFTNu-hqS3bwx7iw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 08:09:35 INFO - mkdir: /builds/slave/test/properties 08:09:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:09:35 INFO - Writing to file /builds/slave/test/properties/build_url 08:09:35 INFO - Contents: 08:09:35 INFO - build_url:https://queue.taskcluster.net/v1/task/FqgNp5jFTNu-hqS3bwx7iw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 08:09:36 INFO - Running post-action listener: _resource_record_post_action 08:09:36 INFO - Running post-action listener: set_extra_try_arguments 08:09:36 INFO - ##### 08:09:36 INFO - ##### Finished download-and-extract step (success) 08:09:36 INFO - ##### 08:09:36 INFO - ##### 08:09:36 INFO - ##### Running populate-webroot step. 08:09:36 INFO - ##### 08:09:36 INFO - Running pre-action listener: _resource_record_pre_action 08:09:36 INFO - Running main action method: populate_webroot 08:09:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:09:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:09:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:09:36 INFO - '--tppagecycles', 08:09:36 INFO - '7'], 08:09:36 INFO - 'tests': ['tsvgm']}}, 08:09:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:09:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:09:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:09:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:09:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:09:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:09:36 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:09:36 INFO - 'talos_options': ['--disable-e10s'], 08:09:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:09:36 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:09:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:09:36 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:09:36 INFO - 'talos_options': ['--disable-e10s'], 08:09:36 INFO - 'tests': ['damp', 'tps']}, 08:09:36 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:09:36 INFO - 'tests': ['damp', 'tps']}, 08:09:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:09:36 INFO - 'tests': ['dromaeo_dom']}, 08:09:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:09:36 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:09:36 INFO - 'tests': ['basic_compositor_video']}, 08:09:36 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:09:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:09:36 INFO - 'tests': ['a11yr', 08:09:36 INFO - 'ts_paint', 08:09:36 INFO - 'tpaint', 08:09:36 INFO - 'sessionrestore', 08:09:36 INFO - 'sessionrestore_no_auto_restore', 08:09:36 INFO - 'tabpaint']}, 08:09:36 INFO - 'other-e10s': {'tests': ['a11yr', 08:09:36 INFO - 'ts_paint', 08:09:36 INFO - 'tpaint', 08:09:36 INFO - 'sessionrestore', 08:09:36 INFO - 'sessionrestore_no_auto_restore', 08:09:36 INFO - 'tabpaint']}, 08:09:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:09:36 INFO - 'ts_paint', 08:09:36 INFO - 'tpaint', 08:09:36 INFO - 'sessionrestore', 08:09:36 INFO - 'sessionrestore_no_auto_restore', 08:09:36 INFO - 'tabpaint']}, 08:09:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:09:36 INFO - 'ts_paint', 08:09:36 INFO - 'tpaint', 08:09:36 INFO - 'sessionrestore', 08:09:36 INFO - 'sessionrestore_no_auto_restore', 08:09:36 INFO - 'tabpaint']}, 08:09:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:09:36 INFO - 'tests': ['a11yr', 08:09:36 INFO - 'ts_paint', 08:09:36 INFO - 'tpaint', 08:09:36 INFO - 'sessionrestore', 08:09:36 INFO - 'sessionrestore_no_auto_restore', 08:09:36 INFO - 'tabpaint']}, 08:09:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:09:36 INFO - 'tests': ['a11yr', 08:09:36 INFO - 'ts_paint', 08:09:36 INFO - 'tpaint', 08:09:36 INFO - 'sessionrestore', 08:09:36 INFO - 'sessionrestore_no_auto_restore', 08:09:36 INFO - 'tabpaint']}, 08:09:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:09:36 INFO - 'tests': ['tsvgx', 08:09:36 INFO - 'tsvgr_opacity', 08:09:36 INFO - 'tart', 08:09:36 INFO - 'tscrollx', 08:09:36 INFO - 'cart']}, 08:09:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:09:36 INFO - 'tsvgr_opacity', 08:09:36 INFO - 'tart', 08:09:36 INFO - 'tscrollx', 08:09:36 INFO - 'cart']}, 08:09:36 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:09:36 INFO - 'talos_options': ['--disable-e10s'], 08:09:36 INFO - 'tests': ['tp5o']}, 08:09:36 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:09:36 INFO - 'tests': ['tp5o']}, 08:09:36 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:09:36 INFO - 'talos_options': ['--disable-e10s', 08:09:36 INFO - '--xperf_path', 08:09:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:09:36 INFO - 'tests': ['tp5n']}, 08:09:36 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:09:36 INFO - 'talos_options': ['--xperf_path', 08:09:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:09:36 INFO - 'tests': ['tp5n']}}, 08:09:36 INFO - 'talos.zip': {'path': '', 08:09:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:09:36 INFO - Running post-action listener: _resource_record_post_action 08:09:36 INFO - ##### 08:09:36 INFO - ##### Finished populate-webroot step (success) 08:09:36 INFO - ##### 08:09:36 INFO - ##### 08:09:36 INFO - ##### Running create-virtualenv step. 08:09:36 INFO - ##### 08:09:36 INFO - Running pre-action listener: _resource_record_pre_action 08:09:36 INFO - Running main action method: create_virtualenv 08:09:36 INFO - Creating virtualenv /builds/slave/test/build/venv 08:09:36 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:09:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:09:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:09:36 INFO - Using real prefix '/tools/python27' 08:09:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:09:37 INFO - Installing distribute.............................................................................................................................................................................................done. 08:09:40 INFO - Installing pip.................done. 08:09:40 INFO - Return code: 0 08:09:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:09: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')]} 08:09:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09: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')]} 08:09:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107276a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10730b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefe3586750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071dad30>, '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 0x106f3fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071d2b20>, '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.9PESWhxtRR/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.gJuvkKr4qo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 08:09:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:09:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:09:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9PESWhxtRR/Render', 08:09:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:40 INFO - 'HOME': '/Users/cltbld', 08:09:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:40 INFO - 'LOGNAME': 'cltbld', 08:09:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:40 INFO - 'MOZ_NO_REMOTE': '1', 08:09:40 INFO - 'NO_EM_RESTART': '1', 08:09:40 INFO - 'PAGER': '/bin/cat', 08:09:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:40 INFO - 'PWD': '/builds/slave/test', 08:09:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:40 INFO - 'SHELL': '/bin/bash', 08:09:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gJuvkKr4qo/Listeners', 08:09:40 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 08:09:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:40 INFO - 'USER': 'cltbld', 08:09:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:40 INFO - 'XPC_FLAGS': '0x0', 08:09:40 INFO - 'XPC_SERVICE_NAME': '0', 08:09:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:40 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:09:40 INFO - Cleaning up... 08:09:40 INFO - Return code: 0 08:09:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:09: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')]} 08:09:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09: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')]} 08:09:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107276a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10730b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefe3586750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071dad30>, '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 0x106f3fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071d2b20>, '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.9PESWhxtRR/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.gJuvkKr4qo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 08:09:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:09:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:09:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9PESWhxtRR/Render', 08:09:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:40 INFO - 'HOME': '/Users/cltbld', 08:09:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:40 INFO - 'LOGNAME': 'cltbld', 08:09:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:40 INFO - 'MOZ_NO_REMOTE': '1', 08:09:40 INFO - 'NO_EM_RESTART': '1', 08:09:40 INFO - 'PAGER': '/bin/cat', 08:09:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:40 INFO - 'PWD': '/builds/slave/test', 08:09:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:40 INFO - 'SHELL': '/bin/bash', 08:09:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gJuvkKr4qo/Listeners', 08:09:40 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 08:09:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:40 INFO - 'USER': 'cltbld', 08:09:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:40 INFO - 'XPC_FLAGS': '0x0', 08:09:40 INFO - 'XPC_SERVICE_NAME': '0', 08:09:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:40 INFO - Downloading/unpacking psutil>=3.1.1 08:09:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:09:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:09:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:09:43 INFO - Installing collected packages: psutil 08:09:43 INFO - Running setup.py install for psutil 08:09:43 INFO - building 'psutil._psutil_osx' extension 08:09:43 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 08:09:44 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 08:09:44 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 08:09:44 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 08:09:44 INFO - building 'psutil._psutil_posix' extension 08:09:44 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 08:09:44 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:09:44 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:09:44 INFO - ^ 08:09:44 INFO - 1 warning generated. 08:09:44 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 08:09:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:09:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:09:44 INFO - Successfully installed psutil 08:09:44 INFO - Cleaning up... 08:09:45 INFO - Return code: 0 08:09:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:09:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:09:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:09:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107276a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10730b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefe3586750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071dad30>, '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 0x106f3fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071d2b20>, '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.9PESWhxtRR/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.gJuvkKr4qo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 08:09:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:09:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:09:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9PESWhxtRR/Render', 08:09:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:45 INFO - 'HOME': '/Users/cltbld', 08:09:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:45 INFO - 'LOGNAME': 'cltbld', 08:09:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:45 INFO - 'MOZ_NO_REMOTE': '1', 08:09:45 INFO - 'NO_EM_RESTART': '1', 08:09:45 INFO - 'PAGER': '/bin/cat', 08:09:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:45 INFO - 'PWD': '/builds/slave/test', 08:09:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:45 INFO - 'SHELL': '/bin/bash', 08:09:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gJuvkKr4qo/Listeners', 08:09:45 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 08:09:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:45 INFO - 'USER': 'cltbld', 08:09:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:45 INFO - 'XPC_FLAGS': '0x0', 08:09:45 INFO - 'XPC_SERVICE_NAME': '0', 08:09:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:09:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:09:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:09:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:09:47 INFO - Installing collected packages: mozsystemmonitor 08:09:47 INFO - Running setup.py install for mozsystemmonitor 08:09:48 INFO - Successfully installed mozsystemmonitor 08:09:48 INFO - Cleaning up... 08:09:48 INFO - Return code: 0 08:09:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:09: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')]} 08:09:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09: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')]} 08:09:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09: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', '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 0x107276a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10730b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefe3586750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071dad30>, '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 0x106f3fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071d2b20>, '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.9PESWhxtRR/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.gJuvkKr4qo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 08:09: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:09: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 jsonschema==2.5.1 08:09:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9PESWhxtRR/Render', 08:09:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:48 INFO - 'HOME': '/Users/cltbld', 08:09:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:48 INFO - 'LOGNAME': 'cltbld', 08:09:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:48 INFO - 'MOZ_NO_REMOTE': '1', 08:09:48 INFO - 'NO_EM_RESTART': '1', 08:09:48 INFO - 'PAGER': '/bin/cat', 08:09:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:48 INFO - 'PWD': '/builds/slave/test', 08:09:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:48 INFO - 'SHELL': '/bin/bash', 08:09:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gJuvkKr4qo/Listeners', 08:09:48 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 08:09:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:48 INFO - 'USER': 'cltbld', 08:09:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:48 INFO - 'XPC_FLAGS': '0x0', 08:09:48 INFO - 'XPC_SERVICE_NAME': '0', 08:09:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:48 INFO - Downloading/unpacking jsonschema==2.5.1 08:09:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:09:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:09:50 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 08:09:50 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 08:09:50 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 08:09:50 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 08:09:50 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 08:09:50 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 08:09:51 INFO - Downloading functools32-3.2.3-2.tar.gz 08:09:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:09:51 INFO - warning: no files found matching '*.txt' 08:09:51 INFO - no previously-included directories found matching 'build' 08:09:51 INFO - no previously-included directories found matching 'dist' 08:09:51 INFO - no previously-included directories found matching '.git*' 08:09:51 INFO - Installing collected packages: jsonschema, functools32 08:09:51 INFO - Running setup.py install for functools32 08:09:51 INFO - warning: no files found matching '*.txt' 08:09:51 INFO - no previously-included directories found matching 'build' 08:09:51 INFO - no previously-included directories found matching 'dist' 08:09:51 INFO - no previously-included directories found matching '.git*' 08:09:51 INFO - Successfully installed jsonschema functools32 08:09:51 INFO - Cleaning up... 08:09:51 INFO - Return code: 0 08:09:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:09:51 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')]} 08:09:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:51 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')]} 08:09:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:51 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 0x107276a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10730b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefe3586750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071dad30>, '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 0x106f3fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071d2b20>, '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.9PESWhxtRR/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.gJuvkKr4qo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 08:09:51 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 08:09:51 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 08:09:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9PESWhxtRR/Render', 08:09:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:51 INFO - 'HOME': '/Users/cltbld', 08:09:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:51 INFO - 'LOGNAME': 'cltbld', 08:09:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:51 INFO - 'MOZ_NO_REMOTE': '1', 08:09:51 INFO - 'NO_EM_RESTART': '1', 08:09:51 INFO - 'PAGER': '/bin/cat', 08:09:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:51 INFO - 'PWD': '/builds/slave/test', 08:09:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:51 INFO - 'SHELL': '/bin/bash', 08:09:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gJuvkKr4qo/Listeners', 08:09:51 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 08:09:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:51 INFO - 'USER': 'cltbld', 08:09:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:51 INFO - 'XPC_FLAGS': '0x0', 08:09:51 INFO - 'XPC_SERVICE_NAME': '0', 08:09:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:51 INFO - Downloading/unpacking blobuploader==1.2.4 08:09:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:54 INFO - Downloading blobuploader-1.2.4.tar.gz 08:09:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:09:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:09: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 08:09: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 08:09: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 08:09: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 08:09: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 08:09: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 08:09:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:09:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:09:55 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 08:09:55 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 08:09:55 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 08:09:55 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 08:09:55 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 08:09:55 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 08:09:55 INFO - Downloading docopt-0.6.1.tar.gz 08:09:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:09:55 INFO - Installing collected packages: blobuploader, requests, docopt 08:09:55 INFO - Running setup.py install for blobuploader 08:09:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:09:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:09:55 INFO - Running setup.py install for requests 08:09:56 INFO - Running setup.py install for docopt 08:09:56 INFO - Successfully installed blobuploader requests docopt 08:09:56 INFO - Cleaning up... 08:09:56 INFO - Return code: 0 08:09:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:09:56 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')]} 08:09:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:56 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')]} 08:09:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:56 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 0x107276a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10730b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefe3586750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071dad30>, '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 0x106f3fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071d2b20>, '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.9PESWhxtRR/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.gJuvkKr4qo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 08:09:56 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 08:09:56 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 08:09:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9PESWhxtRR/Render', 08:09:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:56 INFO - 'HOME': '/Users/cltbld', 08:09:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:56 INFO - 'LOGNAME': 'cltbld', 08:09:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:56 INFO - 'MOZ_NO_REMOTE': '1', 08:09:56 INFO - 'NO_EM_RESTART': '1', 08:09:56 INFO - 'PAGER': '/bin/cat', 08:09:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:56 INFO - 'PWD': '/builds/slave/test', 08:09:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:56 INFO - 'SHELL': '/bin/bash', 08:09:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gJuvkKr4qo/Listeners', 08:09:56 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 08:09:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:56 INFO - 'USER': 'cltbld', 08:09:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:56 INFO - 'XPC_FLAGS': '0x0', 08:09:56 INFO - 'XPC_SERVICE_NAME': '0', 08:09:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:09:57 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-ZF2BxK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:09:57 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-DmAIhc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:09:57 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-g2hhrp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:09:57 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-G77HLe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:09:57 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-_4rUiK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:09:57 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-5EClIB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:09:57 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-4ReND7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:09:57 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-y9Cafk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:09:57 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-Evel18-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:09:58 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-3_aCqi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:09:58 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-cQWXx9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:09:58 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-YpcfqW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:09:58 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-8BR0J3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:09:58 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-fi21R0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:09:58 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-cTQLMU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:09:58 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-Y4r0Lw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:09:58 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-yLQNqH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:09:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:09:58 INFO - Running setup.py install for manifestparser 08:09:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:09:59 INFO - Running setup.py install for mozcrash 08:09:59 INFO - Running setup.py install for mozdebug 08:09:59 INFO - Running setup.py install for mozdevice 08:09:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:09:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:09:59 INFO - Running setup.py install for mozfile 08:09:59 INFO - Running setup.py install for mozhttpd 08:09:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:09:59 INFO - Running setup.py install for mozinfo 08:09:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:09:59 INFO - Running setup.py install for mozInstall 08:10:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:10:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:10:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:10:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:10:00 INFO - Running setup.py install for mozleak 08:10:00 INFO - Running setup.py install for mozlog 08:10:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:10:00 INFO - Running setup.py install for moznetwork 08:10:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:10:00 INFO - Running setup.py install for mozprocess 08:10:00 INFO - Running setup.py install for mozprofile 08:10:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:10:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:10:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:10:00 INFO - Running setup.py install for mozrunner 08:10:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:10:01 INFO - Running setup.py install for mozscreenshot 08:10:01 INFO - Running setup.py install for moztest 08:10:01 INFO - Running setup.py install for mozversion 08:10:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:10:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:10:01 INFO - Cleaning up... 08:10:01 INFO - Return code: 0 08:10:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:10:01 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')]} 08:10:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:01 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')]} 08:10:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:01 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 0x107276a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10730b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefe3586750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071dad30>, '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 0x106f3fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071d2b20>, '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.9PESWhxtRR/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.gJuvkKr4qo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 08:10:01 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 08:10:01 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 08:10:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9PESWhxtRR/Render', 08:10:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:01 INFO - 'HOME': '/Users/cltbld', 08:10:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:01 INFO - 'LOGNAME': 'cltbld', 08:10:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:01 INFO - 'MOZ_NO_REMOTE': '1', 08:10:01 INFO - 'NO_EM_RESTART': '1', 08:10:01 INFO - 'PAGER': '/bin/cat', 08:10:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:01 INFO - 'PWD': '/builds/slave/test', 08:10:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:01 INFO - 'SHELL': '/bin/bash', 08:10:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gJuvkKr4qo/Listeners', 08:10:01 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 08:10:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:01 INFO - 'USER': 'cltbld', 08:10:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:01 INFO - 'XPC_FLAGS': '0x0', 08:10:01 INFO - 'XPC_SERVICE_NAME': '0', 08:10:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:10:01 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-RA209w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:10:01 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)) 08:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:10:01 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-Lokiok-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:10:01 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)) 08:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:10:01 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-Rw3YSy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:10:02 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)) 08:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:10:02 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-ljpL_m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:10:02 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)) 08:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:10:02 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-yrvOcj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:10:02 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)) 08:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:10:02 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-NwcbBG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:10:02 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)) 08:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:10:02 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-AyCPu6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:10:02 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)) 08:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:10:02 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-VZPlC9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:10:02 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)) 08:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:10:02 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-klOgNp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:10:02 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)) 08:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:10:02 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-k92hHO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:10:02 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)) 08:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:10:02 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-dJlkZ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:10:02 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)) 08:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:10:02 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-YxB38e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:10:03 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)) 08:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:10:03 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-LzBK9A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:10:03 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)) 08:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:10:03 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-P56U0R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:10:03 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)) 08:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:10:03 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-lYNiL5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:10:03 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)) 08:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:10:03 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-u0VkVP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:10:03 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)) 08:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:10:03 INFO - Running setup.py (path:/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/pip-7DdM_e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:10:03 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)) 08:10:03 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)) 08:10:03 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)) 08:10:03 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)) 08:10:03 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)) 08:10:03 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)) 08:10:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:10: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 08:10: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 08:10: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 08:10: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 08:10: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 08:10: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 08:10:06 INFO - Downloading blessings-1.6.tar.gz 08:10:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:10:06 INFO - Installing collected packages: blessings 08:10:06 INFO - Running setup.py install for blessings 08:10:06 INFO - Successfully installed blessings 08:10:06 INFO - Cleaning up... 08:10:06 INFO - Return code: 0 08:10:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:10:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:10:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:10:07 INFO - Reading from file tmpfile_stdout 08:10:07 INFO - Current package versions: 08:10:07 INFO - blessings == 1.6 08:10:07 INFO - blobuploader == 1.2.4 08:10:07 INFO - docopt == 0.6.1 08:10:07 INFO - functools32 == 3.2.3-2 08:10:07 INFO - jsonschema == 2.5.1 08:10:07 INFO - manifestparser == 1.1 08:10:07 INFO - mozInstall == 1.12 08:10:07 INFO - mozcrash == 0.17 08:10:07 INFO - mozdebug == 0.1 08:10:07 INFO - mozdevice == 0.48 08:10:07 INFO - mozfile == 1.2 08:10:07 INFO - mozhttpd == 0.7 08:10:07 INFO - mozinfo == 0.9 08:10:07 INFO - mozleak == 0.1 08:10:07 INFO - mozlog == 3.2 08:10:07 INFO - moznetwork == 0.27 08:10:07 INFO - mozprocess == 0.23 08:10:07 INFO - mozprofile == 0.28 08:10:07 INFO - mozrunner == 6.12 08:10:07 INFO - mozscreenshot == 0.1 08:10:07 INFO - mozsystemmonitor == 0.3 08:10:07 INFO - moztest == 0.7 08:10:07 INFO - mozversion == 1.4 08:10:07 INFO - psutil == 3.1.1 08:10:07 INFO - requests == 1.2.3 08:10:07 INFO - wsgiref == 0.1.2 08:10:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:10:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:07 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 0x107276a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10730b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefe3586750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071dad30>, '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 0x106f3fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071d2b20>, '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.9PESWhxtRR/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.gJuvkKr4qo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 08:10:07 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 08:10:07 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 08:10:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9PESWhxtRR/Render', 08:10:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:07 INFO - 'HOME': '/Users/cltbld', 08:10:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:07 INFO - 'LOGNAME': 'cltbld', 08:10:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:07 INFO - 'MOZ_NO_REMOTE': '1', 08:10:07 INFO - 'NO_EM_RESTART': '1', 08:10:07 INFO - 'PAGER': '/bin/cat', 08:10:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:07 INFO - 'PWD': '/builds/slave/test', 08:10:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:07 INFO - 'SHELL': '/bin/bash', 08:10:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gJuvkKr4qo/Listeners', 08:10:07 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 08:10:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:07 INFO - 'USER': 'cltbld', 08:10:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:07 INFO - 'XPC_FLAGS': '0x0', 08:10:07 INFO - 'XPC_SERVICE_NAME': '0', 08:10:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:07 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)) 08:10:07 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)) 08:10:07 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)) 08:10:07 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)) 08:10:07 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)) 08:10:07 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)) 08:10:07 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)) 08:10:07 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)) 08:10:07 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)) 08:10:07 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)) 08:10:07 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)) 08:10:07 INFO - Cleaning up... 08:10:07 INFO - Return code: 0 08:10:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:10:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107276a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10730b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fefe3586750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071dad30>, '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 0x106f3fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071d2b20>, '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.9PESWhxtRR/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.gJuvkKr4qo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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 08:10:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:10:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:10:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9PESWhxtRR/Render', 08:10:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:07 INFO - 'HOME': '/Users/cltbld', 08:10:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:07 INFO - 'LOGNAME': 'cltbld', 08:10:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:07 INFO - 'MOZ_NO_REMOTE': '1', 08:10:07 INFO - 'NO_EM_RESTART': '1', 08:10:07 INFO - 'PAGER': '/bin/cat', 08:10:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:07 INFO - 'PWD': '/builds/slave/test', 08:10:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:07 INFO - 'SHELL': '/bin/bash', 08:10:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gJuvkKr4qo/Listeners', 08:10:07 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 08:10:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:07 INFO - 'USER': 'cltbld', 08:10:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:07 INFO - 'XPC_FLAGS': '0x0', 08:10:07 INFO - 'XPC_SERVICE_NAME': '0', 08:10:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:10:07 INFO - Cleaning up... 08:10:07 INFO - Return code: 0 08:10:07 INFO - Running post-action listener: _resource_record_post_action 08:10:07 INFO - Running post-action listener: _start_resource_monitoring 08:10:07 INFO - Starting resource monitoring. 08:10:07 INFO - ##### 08:10:07 INFO - ##### Finished create-virtualenv step (success) 08:10:07 INFO - ##### 08:10:07 INFO - ##### 08:10:07 INFO - ##### Running install step. 08:10:07 INFO - ##### 08:10:07 INFO - Running pre-action listener: _resource_record_pre_action 08:10:07 INFO - Running main action method: install 08:10:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:10:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:10:07 INFO - Reading from file tmpfile_stdout 08:10:07 INFO - Detecting whether we're running mozinstall >=1.0... 08:10:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:10:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:10:07 INFO - Reading from file tmpfile_stdout 08:10:07 INFO - Output received: 08:10:07 INFO - Usage: mozinstall [options] installer 08:10:07 INFO - Options: 08:10:07 INFO - -h, --help show this help message and exit 08:10:07 INFO - -d DEST, --destination=DEST 08:10:07 INFO - Directory to install application into. [default: 08:10:07 INFO - "/builds/slave/test"] 08:10:07 INFO - --app=APP Application being installed. [default: firefox] 08:10:07 INFO - mkdir: /builds/slave/test/build/application 08:10:07 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'] 08:10:07 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 08:10:42 INFO - Reading from file tmpfile_stdout 08:10:42 INFO - Output received: 08:10:42 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:10:42 INFO - Running post-action listener: _resource_record_post_action 08:10:42 INFO - ##### 08:10:42 INFO - ##### Finished install step (success) 08:10:42 INFO - ##### 08:10:42 INFO - ##### 08:10:42 INFO - ##### Running run-tests step. 08:10:42 INFO - ##### 08:10:42 INFO - Running pre-action listener: _resource_record_pre_action 08:10:42 INFO - Running main action method: run_tests 08:10:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:10:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:10:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:10:42 INFO - 2016-07-07 08:10:42.658 screenresolution[1641:7727] starting screenresolution argv=screenresolution get 08:10:42 INFO - 2016-07-07 08:10:42.661 screenresolution[1641:7727] Display 0: 1600x1200x32@60 08:10:42 INFO - 2016-07-07 08:10:42.666 screenresolution[1642:7731] starting screenresolution argv=screenresolution list 08:10:42 INFO - Available Modes on Display 0 08:10:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:10:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:10:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:10:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:10:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:10:42 INFO - Intel Iris: 08:10:42 INFO - Chipset Model: Intel Iris 08:10:42 INFO - Type: GPU 08:10:42 INFO - Bus: Built-In 08:10:42 INFO - VRAM (Dynamic, Max): 1536 MB 08:10:42 INFO - Vendor: Intel (0x8086) 08:10:42 INFO - Device ID: 0x0a2e 08:10:42 INFO - Revision ID: 0x0009 08:10:42 INFO - Displays: 08:10:42 INFO - Display: 08:10:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:10:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:10:42 INFO - Display Serial Number: 1600x1200 60 08:10:42 INFO - Main Display: Yes 08:10:42 INFO - Mirror: Off 08:10:42 INFO - Online: Yes 08:10:42 INFO - Rotation: Supported 08:10:42 INFO - Return code: 0 08:10:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:10:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:10:42 INFO - Python 2.7.3 08:10:42 INFO - Return code: 0 08:10:42 INFO - grabbing minidump binary from tooltool 08:10:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:42 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 0x1071dad30>, '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 0x106f3fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071d2b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:10:42 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 08:10:42 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 08:10:42 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 08:10:42 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:10:43 INFO - Return code: 0 08:10:43 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:10:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:10:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:10:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:10:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:10:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FqgNp5jFTNu-hqS3bwx7iw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0166', '--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 08:10:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FqgNp5jFTNu-hqS3bwx7iw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0166 --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 08:10:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9PESWhxtRR/Render', 08:10:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:43 INFO - 'HOME': '/Users/cltbld', 08:10:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:43 INFO - 'LOGNAME': 'cltbld', 08:10:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:10:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:10:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:43 INFO - 'MOZ_NO_REMOTE': '1', 08:10:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:10:43 INFO - 'NO_EM_RESTART': '1', 08:10:43 INFO - 'PAGER': '/bin/cat', 08:10:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:43 INFO - 'PWD': '/builds/slave/test', 08:10:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:10:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:43 INFO - 'SHELL': '/bin/bash', 08:10:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gJuvkKr4qo/Listeners', 08:10:43 INFO - 'TMPDIR': '/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/', 08:10:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:43 INFO - 'USER': 'cltbld', 08:10:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:43 INFO - 'XPC_FLAGS': '0x0', 08:10:43 INFO - 'XPC_SERVICE_NAME': '0', 08:10:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:43 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FqgNp5jFTNu-hqS3bwx7iw/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0166', '--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 08:10:43 INFO - mozversion application_buildid: 20160707054022 08:10:43 INFO - mozversion application_changeset: ec92630e4c635ef1fbaac6115c6a123de1fd5d28 08:10:43 INFO - mozversion application_display_name: Nightly 08:10:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:10:43 INFO - mozversion application_name: Firefox 08:10:43 INFO - mozversion application_remotingname: firefox 08:10:43 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 08:10:43 INFO - mozversion application_vendor: Mozilla 08:10:43 INFO - mozversion application_version: 50.0a1 08:10:43 INFO - mozversion platform_buildid: 20160707054022 08:10:43 INFO - mozversion platform_changeset: ec92630e4c635ef1fbaac6115c6a123de1fd5d28 08:10:43 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 08:10:43 INFO - mozversion platform_version: 50.0a1 08:10:43 INFO - using testdate: 1467904243 08:10:43 INFO - actual date: 1467904243 08:10:43 INFO - starting webserver on 'localhost:49271' 08:10:43 INFO - SUITE-START | Running 2 tests 08:10:43 INFO - TEST-START | dromaeo_css 08:10:43 INFO - operating with platform_type : mac_ 08:10:43 INFO - Initialising browser for dromaeo_css test... 08:10:43 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/tmpoCYoGg/profile http://localhost:49271/getInfo.html) 08:10:45 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 08:10:45 INFO - PROCESS | 1655 | colorDepth:24 08:10:45 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 08:10:45 INFO - PROCESS | 1655 | __metrics 08:10:45 INFO - PROCESS | 1655 | 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" 08:10:45 INFO - PROCESS | 1655 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:10:46 INFO - TEST-INFO | 1655: exit 0 08:10:46 INFO - Browser initialized. 08:10:46 INFO - Running cycle 1/1 for dromaeo_css test... 08:10:46 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/tmpoCYoGg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:10:58 INFO - PROCESS | 1657 | RSS: Main: 243650560 08:10:58 INFO - PROCESS | 1657 | 08:11:50 INFO - PROCESS | 1657 | 1467904310058 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] 08:14:00 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 08:14:01 INFO - PROCESS | 1657 | RSS: Main: 356638720 08:14:01 INFO - PROCESS | 1657 | 08:17:04 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 08:17:05 INFO - PROCESS | 1657 | RSS: Main: 375926784 08:17:05 INFO - PROCESS | 1657 | 08:20:07 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 08:20:08 INFO - PROCESS | 1657 | RSS: Main: 370208768 08:20:08 INFO - PROCESS | 1657 | 08:23:10 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 08:23:11 INFO - PROCESS | 1657 | RSS: Main: 370462720 08:23:11 INFO - PROCESS | 1657 | 08:26:14 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 08:26:15 INFO - PROCESS | 1657 | RSS: Main: 336605184 08:26:15 INFO - PROCESS | 1657 | 08:29:18 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 08:29:18 INFO - PROCESS | 1657 | RSS: Main: 401260544 08:29:18 INFO - PROCESS | 1657 | 08:29:18 INFO - PROCESS | 1657 | __start_tp_report 08:29:18 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 08:29:18 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 08:29:18 INFO - PROCESS | 1657 | |i|pagename|runs| 08:29:18 INFO - PROCESS | 1657 | |0;dojo.html;3333.1167240681207;3346.8493917773703;3369.8652053917835;3383;3401.1327111586543;5585.832425027245;5609.915851262229;5651.660900345983;5698.487136157745;5746.597738158331;5688.459596338341;5729.828105156842;5803.6227645203035;5820.272465941757;5864.032434648278;5153.948460515385;5173.3016042834115;5237.397699264595;5247.816326428576;5339.145736682131;4454.844080457185;5302.257683924254;5306.283651707028;5362.597805164609;5387.19192121183;5133.281340612336;5158.484151584849;5192.324997750268;5245.055889939809;5261.079311120577;5222.817201397971;5226.268322434862;5231.764570594332;5353.357597088336;5372.623916325859;5221.869453263661;5235.790568377261;5253.86865328366;5311.336082989626;5326.786928522854;5578.944210557883;5646.378898321182;5732.197492351074;5757.453041961007;5804.216430781833;6140.504248697391;6207.658578778165;6223.688815559204;6243.001119820852;6261.5929964552615;6279.811605651837;6323.272823625296;6331.113644089831;6349.714262858182;6375.139356186902;5004.474530174352;5585.413531579207;5838.591298609099;5865.76536938522;5867.647941123503;6280.214973128359;6339.397757213057;6339.714712837934;6353.30113687494;6408.038794180817;6067.848303792441;6101.115338275925;6112.541559383064;6116.479212242861;6125.571210015256;6118.082287656798;6164.630122192682;6263.467605253513;6266.811995640138;6332.810015699444;6102.786402475981;6138.324784273726;6142.375998161017;6142.744345496893;6171.783987560414;6253.1245625612455;6261.780837670751;6267.843303917439;6274.152989346471;6284.748610055638;4975.328330675385;4977.278294647256;4981.452040275567;4991.276264941635;5011.974940125276;4313.245182093121;4319.3952846601505;4401.515833258339;4492.797824097924;4523.276275795923;5713.485786279255;5723.971380143156;5731.484166425042;5737.340205876378;5784.074548072288;6290.779822706183;6311;6313.810585682437;6355.809325720235;6360.4911607072245;5551.167324901297;5647.519960803378;5682.346530149003;5689.516391106719;5721.77112915487;5443.945560544423;5446.210299506629;5452.182172674051;5461.15352120422;5462.672239665632;6326.49388048955;6331.778387756406;6337.493000559944;6339.492840572835;6341.207349081365;6134.478569321657;6202.596831205957;6211.844703882439;6276.152719382825;6309.7160627770945;6335.588186767845;6349.587276826992;6353;6353.841153971188;6376.713047912948;6224.097505861715;6226.034964580497;6230.190075290183;6230.844228894314;6314.526410519137;6132.8773424532155;6150.7539698411565;6169.50643948474;6220.844478887883;6301;4799.544043315879;5930.673812940329;5977.163197152315;6097.420745029215;6105.206323177958;5922.318933322551;6091.664958427152;6097.603655762361;6122.969385153046;6131.172291740558;5475.589330800286;5837.29952405714;5856.3558008618165;5857.941420585739;5891.617044892068;6015.699215039318;6038.788642397495;6044.032954727222;6050.878982420415;6082.90875636857;5785.218995435563;5840.620359676607;5889.676067816311;5898.233229680114;5911.527077833849 08:29:18 INFO - PROCESS | 1657 | |1;ext.html;511.6392942975202;513.8766695992961;541.9160030195321;546.1098409592364;553.4050895287567;30488;32380.514292285636;41924.58075419249;43045.13909721812;43062;36145;44984;45071.77464112683;45188.3221751674;45197.870053248684;9814.509274536298;10013.849792253122;10021.799564008716;10026.749331266703;10036.29745917786;7500.399968002546;9027.729168124966;11968.70078248042;12186.390680465985;12194.695132621711;12108.455119519598;12179.57371491998;12181.634550963485;12187.146899717023;12207.962323202539;8738.475691458532;8919.910800891972;12092.576759813408;12221.816672749916;12226.266424014542;8893.822123557557;8941.105889411007;11737.706557336049;12022;12046.698832529257;42035;42304.365434518506;42402.57597424017;42462.57537424617;42477.362839557434;3417.470292104728;3473.774204676713;3884.261990221877;4116.238495878242;4171.561985991458;31805;32881.835590822135;33012.6698733012;33242.83378583092;33253.16867078342;3265.6407795142513;3393.898183054512;3426.109211604976;3457.0320310313155;3472.618012018676;532634;535575;535867;536201;536938;62315.6884215576;62417.68791156106;62795;62805.68597156985;62863;65683.67158164179;65838.34161658418;65857;65918.67040664767;66071;16458.917705411637;16717.826682531195;16738;16746.246418910934;16777;11730.237534560227;11948.880511194777;12604.243745375306;13031.869681303255;13109.475620975054;16834.831651683326;17397.78141043911;17414.390496332566;17423.825761742475;17439.65120697579;6107.9616465201025;6281.811545653546;6397.776077837253;6957.234704182536;6981.765834932773;947.2847999760137;953.880764904387;995.0845222395413;1038.3406536849154;1042.6559235452282;26661.866690666422;29250.683719232686;30985.845070774503;31110.40012797718;31229.843850781053;864.8572985457329;1060.4909643371225;1065.3874022436976;1176.5411489519095;1201.225209739713;13708.52020179289;15325.846741532441;15466.458673946583;15583;15900.363985440454;571654;575439;576194;577852.1107394436;577878;58843.70578147082;59204.40795592075;59239.111413328836;59413.40586594079;59491.10763338553;1297.8118172864881;1402.2778269191374;1418.44680574576;1436.4397884825134;1449.282605110477;27048;31111;31299.687003130588;31450.21374465565;32294.515582266755;43614;53102.67243318948;53522.197167043305;53642.463575365306;53654.463455364945;588916;593241;593709;594090.0295498495;595811;26330.86834565815;26815.865920670272;27287.590686139327;27450.588241176;28245.43509129847;48483.51516484931;49186.754066230875;49586.25620615766;49638.007239855724;50190.498095018585;39031.804840975616;39984.600153998086;40958.79520602378;42085.57914420817;42356.57643423526;40880.59119408887;41141.382879256234;41562.1687566253;41651.68052366037;42164.578354216064;39377.60622393739;39683.60316396799;39876.60123398729;40038.1992360157;40104;27731.44537109287;31887.043388698377;35115.82442087875;36160;36410;25489;25854;26128.216153515426;26622.7337726628;26706.59940100939 08:29:18 INFO - PROCESS | 1657 | |2;jquery.html;323.94169049571076;362.633739922678;389.1749491078912;394.70594407166664;411.9773412462315;15200;15757.684846303082;21291.36125916225;21554.814485203326;21582.784172158274;20516.48708782281;21142.788572114314;21156.788432115714;21186.788132118676;21197.470063248427;5746.655200687972;5934.643921364732;6171.290301615327;6180.505559555225;6186.133941248207;6013.488853447462;6060.575759696823;6101.969490152543;6115.847103822395;6122.877542449149;6035.185249991261;6048.697565121748;6097.054956481752;6689.264180940093;6690.4313133383475;6572.375624315682;6689.130413046321;6713.462922966175;6719;6746.763863264811;5822.883542329172;5856.853578660525;5895.174675545427;5898.203742494794;5907.468327850471;12868.806967895489;13062.150960187557;19102.61794764111;25130.874345628337;25179.748202517923;2198.714167158259;2249.3814201094665;2251.2683377902085;2324.2097686786574;2704.8782804773637;9785.412875227508;9926.851097233548;10393.84409233862;12004.339761313124;14718.116912985255;1654.9007059576463;1824.1335365701318;1886.3303527247767;1923.0961448119363;1942.689169732836;101659.98340016653;121263;125703;128087;135888;10317.329373590692;10568.78458977212;11025.03098884401;11305.00417389376;11394.948909196259;14159.079659822079;14182.716345673029;14298;14305.713885722436;14313;7384.298491643285;7929.643166057426;8191.836163276701;8380.832383352421;8514.659413623509;8294.460860044077;8336.541490217975;8345.332373410112;8393.454425462325;8451.11263317355;3618.2582570572968;3627;4439.247666446824;4441.822327106879;4501.459824820977;1573.622330640661;1847.8429333506801;1887.169645356039;1888.1975160556983;1895.3271588586128;895.2927187521785;941.3598752847992;988.2538683294067;998.8202123617818;1021.1830535571513;14712.926435367968;20275.377969762383;20529.076191571414;21118.260860869796;21235.46911327229;939.4175611121012;958.1999030809213;961.6682244625594;968.9467079310706;1026.9948650256702;10928.398938058324;11050.474108660841;11102.222844400976;11116.333020018825;11158.442077896072;537417;586286;621521;623936;628074;75078.8738168928;101485;125403;127719.44561108726;131377;955.7849483866074;991.4101109839634;1037.3983089807894;1052.0531521630442;1058.2433560004479;19253.956669099647;19466.707999380305;19592.60814783668;19598.902005489883;19629.8037019628;55567.44432555623;55888;56233.71883140558;58586.4141358581;58880;81487.18512815033;81560.18439815525;82482.58758706169;83580.58209708912;83816.58091709502;6934.791956241321;7014.894776578458;7309.720798860285;7399.704011839466;8126.187381261827;11016.669499915015;13068.869311306766;13178.275194864374;13393.464261429433;13433.126846754929;11402.258853174517;11490.82763758561;11495.425228738697;11535.884641153481;11674.94162529182;11243.718907027389;11469.885301146882;11519.30884146954;11537.25007874519;11787.705307367385;11689.88946050124;11938.1046421517;11940.283582985048;11954.58158964432;11980.400979951142;7339.853202935805;7387.704491820267;7411.184769675224;7468.663910123949;7580.090389153337;10656.413897235727;11344.149188810707;11520.711982200177;11544.653660390202;11567.76864462723 08:29:18 INFO - PROCESS | 1657 | |3;mootools.html;1553.4174684493314;1567.059764141515;1757.1829099468744;1867.4771064102056;1896.0235478728457;8116.39127065471;8365.874511882326;8745.737627871174;8755.912440875589;8764.298856091506;7656.502327348732;7782.922170778291;7910.762677119681;7912.208779122099;7997.320227780631;2101.3275751759443;2271.7501074881748;2352.1061996441326;2352.7411984681758;2357.5874222011166;2326.9650955235684;2337.4740683346195;2358.917437889674;2360.173939121311;2366.0181024874655;2051.4358551398336;2337.9766202338014;2341.800946919514;2343.121329501437;2344.6248600223967;1912.416712902564;2310.318456055468;2328.84862483938;2330.6037973544367;2334.299710086967;2307.699999000119;2322.0131444136173;2322.372959300996;2323.407531079564;2362.303120579433;7447.702091916317;8720.694775682885;8775.034746177915;8776.254018408443;8815.986161591434;5114.667546609458;5204.1673332266655;5256.553192978601;5261.0793111205385;5265.894682106336;3462.3767721810063;3536.045267777711;3599.6220396858216;3605.981970090159;3610.5667319921513;1066.1768376076918;1081.0972837680547;1088.9782204355947;1105.2760441910561;1109.4619109731773;11284.548618055267;11324.207305488619;11333.716657083638;11344.262622929484;11366;3581.3553560358864;4528.547145285445;4608.470025946992;4614.7461889595725;4619.330197121399;9700.417974921527;9745.317827752131;9758.463284519277;9760.365576237522;9770.804583908282;5214.270002199695;5233.685978841282;5236.607254455931;5239.869003274948;5242.947570524246;4175.91648167035;4664.883377915511;4788.186008378514;4807.95192048075;4823.879403014953;3890.9610903891166;3948.4866967294643;4000.259951908848;4002.2595819773437;4043;1061.7292590389402;1263.9115261931577;1265.3799638177227;1282.3331867429072;1282.6152154353656;862.981681615687;911.3757076402685;935.4714586258742;942.7548837302351;943.9433633981984;4504.864854054384;5115.514026167508;5858.091995740667;6056.515478761688;6222.657753823492;781.5662307419436;941.6939494664261;977.1401166973018;987.5457289646699;989.9653512127041;3821.8662346817728;3928.2929072767174;3955.881323560298;3970.583088775637;3970.622790834866;10097.899021009844;10500.422476763852;10728.088112510368;10782.029617334321;10804;10609.15126789855;10657.467126643636;10746.462676866126;10746.979036991474;10762.3542587445;7857.253560911704;8202.794930126794;8212;8223.917760822435;8228.711995080144;5709.400512946081;5796.710164491674;5857.355690873916;5910.290765108214;5964.7912323068485;9763.365381250196;9832.01679832011;9844.704658860246;9860.506974651382;9880.555375008285;9622.326437149331;9919.305648604817;10020.749481263027;10109.79780404373;10139.79720405573;6524.119243902012;6712.664366781543;6723.562968407038;7030.086088808423;7164.534305270141;7889.329407000239;7968.880466793119;8120.715774947848;8396.538190399586;8441.155884411755;7372.299631534996;7464.514806537558;7474.887876681963;7602.50583712057;7638.427117966286;7325.084364454443;7389;7396.593187374746;7528.548287102792;7557.508761930457;6885.724571017103;6936.826579335557;6983.127109111361;6999.440044796303;7016.22821489646;4856.562909338035;5003.899922001612;5047.470015648304;5074.517920797518;5120.743962801919;4346.174226896879;5046.243063540498;5122.871928201825;5152.149895267239;5253.579713622978 08:29:18 INFO - PROCESS | 1657 | |4;prototype.html;298.54024801805207;304.50517908398854;314.6051705110086;316.3609508792238;317.8156669131904;5606.467385598372;5607.243022192008;5623.606347555673;5632.915506267409;5673.2908386451745;5257.947420525794;5307.283516725241;5435.668261275993;5531.197976293435;5545.3622833374175;4153.709240353177;4201.6008479194425;4208.200441916041;4244.363345498169;4255.40424340591;3483.4078206704794;4225.767288024664;4234.576542345756;4256.702030857826;4269.487661480611;3862.4206369044728;3900.8829735107993;4187.497500299968;4196.790160491979;4198.916021679564;3849;4179.644730197906;4187.371894215861;4188;4193.182329445759;3202.343519578504;3648.835802388899;3651.926961460756;3686.4286035664513;3701.3892707703467;4335.436393268855;4462;5286.814961476368;5439.945600543983;5486.341639003305;3858.3633700439395;4428.98133429313;4677.298405239208;4812.205986012304;4833.734144622044;4905.681130726492;5518.896220755824;5854.385289544579;5883.411658834125;5901.078965911858;3308.3217940322174;3782.546094468654;3966.0566322502054;4041.4341992121126;4060.7360521566;5025.894303253279;5282.867928301785;5689.658620482743;6075.726592303313;6083.239595050619;3879.6120387960977;3993.542357039718;3998.28030954431;4019.3167161582014;4036.0918793271867;3253.8936761501204;3523.7842944184217;3750.268697603941;4058.817353219112;4068.3287257602756;4454.710443821141;4490.955090449053;4493.876530867283;4704.600108990706;4717.315989181549;4867.488913664085;4873.488283730228;5846.327672317738;5909.852253693692;5910.34986151523;1169.9707507312385;1226.877312268766;1313.638749343919;1370.4929176204669;1388.5140200929593;786.6342150899859;848.1730312944829;866.2853146154448;876.9476628046369;926.4811705444972;4534.569215924482;5051.358308549736;5107.901801112705;5416.187571864252;5458.781648734085;4946.950530494649;5958.761649534057;5962.40375962409;6000.33996260411;6069.605475644069;4693.741844198602;5076.390833100052;5381.730913454312;5390.328998010551;5400.702961337086;2748.40909204522;2796.9930824903017;2820.9858950705116;2994.876921154567;3096.349766549005;5868.215001324122;5963.5825492216;6176.552881925007;6232.407921247474;6327.3988971047675;6259.405356491126;6296.842578935563;6302.684865756693;6335.714892829834;6343;6060.393960603904;6300.30696623371;6306.810795676137;6413.294537600954;6424.3254458282145;5043.697378157322;6080.537382360151;6082.232584174279;6121.663308518075;6127.387261273837;5077.568406685473;5560.805371811967;5827.863566604471;6049.697515124314;6055.788047418319;5314.5216930476445;5331.280277162688;5967.910481342697;6280.905786413299;6297.433231009231;3649.5620525537106;3756.079760458705;3961.4652021976663;4004.719669623215;4049.3318602430268;5368.543673787772;6140.267580926445;6151.354107818794;6156.907646385397;6175;4938.506149385033;5574.832755017356;5583.832485025456;5877.73550190251;5896.262967129109;4911.435184953776;5859.736311866061;5877;5880.0889710845;5896.49639341965;4523.90952180948;5324.414314425488;5429.40276569585;5707.6290041147195;5845.824625261249;5778.9547316877515;5840.620359676607;5867.946218823548;5871.911921321098;5882.499962509532;5452.509274165344;5576.525995290446;5767.769289228551;5773.624714393551;5789.478946894799 08:29:18 INFO - PROCESS | 1657 | |5;yui.html;552.0036334416379;701.9473539484537;760.1979911193688;775.9728409505665;877.8376000439919;15139.621509462255;15679.137647429401;15733.842661573366;16437.506874793744;16477.17614119295;12529.624111276653;14599.124052556828;14772;14806.81545476362;15000.624984375396;871.1114663043708;897.5332826929993;899.3075331994361;900.7703035725881;901.0808974845653;849.5667209723024;866.5147517390282;866.7009881590841;867.111211008718;868.288003836854;923.5843870258377;926.0878035135386;926.1618235496886;927.1933417926414;928.4800511713428;915.9725208243697;916.3585490156884;916.3585490156884;916.9037251088673;917.9993806750674;679.7145199016376;682.2801943949132;682.5188242289174;682.7610336382276;684.7979845945458;13331.333433328391;13669.316534173251;13671.658208544766;13697.931510342385;13714.862851371458;12188.939055304756;12219.572314969022;12246.632601021984;12516.874831251662;12589.055820813475;11030.944845275722;11242.325460472397;11249.987501124939;11332.320060796379;11734.354610496419;3789.7157713171346;3792.829322680486;3797.905052373713;3824.368979118442;3957.960420395788;84233.57883210544;87574;103199.99999999924;111360;114887;11510.251833630788;11857.940710296394;11920.76158476843;12056.216345937486;12064.638060858188;19519.609607807764;21028.684569731467;23784.881075594512;23816.40458988539;23858.52282954331;6834.384905358542;8190.8771368429525;8293.129221431664;8346.332293416634;8435.662573497115;3843.5003449551377;4386.649068074546;4468.262736648417;4607.285870690049;4752.714837109785;2938.7795915306438;3404.097914052778;3533.699635530957;3539.4513850353237;3541;641.7016087519326;652.292262894755;658.3811217455576;668.3951024322995;671.4796033074309;505.99241011384856;658.5719282466436;659.3735950846715;707.5966698981531;716.8745469542913;8296.25333719956;8997.19025287714;9234.676786312446;9403;9744.561494732754;603.5563860562484;632.8987959000841;652.3737212276174;659.36700767263;706.4489698035541;4595.241785105421;4600.194965880957;4618.51505591908;4645.9303110453375;4751.6436267279405;146865;147292.26353868376;147344;152217;156196.21901890417;85582.57208713917;95152.52423737837;120411;123631;130646;98763;107897.4605126985;109472.4526377363;113094.43452782519;117103;8540.487570745776;9676.425575895088;9798.31411801167;9823.165030972448;9903.356281841658;54065;58732.70633646975;64825.37936551624;65827;67789.66105169442;96420;98536;100515;101159.49420252851;102559;11520.94239528797;13126.803097953729;13525.661858453617;13869.445222191;13937.58187254384;15274.694506110038;15334.079955202724;15652;15709.528714138594;15725.842741572438;12999.350032498527;13269.535566255136;13319.600411987656;13327.733445330845;13762.105463144864;13920.303984800921;14213.786793198316;14544.98185127031;14575.344109514885;14808.407663693331;13702.65743356424;13882;13967.650808729863;14016.229107399191;14038.649033774238;9596.256871215264;10737.838932415863;11097.500612472297;11589.0888906513;11885;11432.542698291974;11513.30920144794;11514.20263923053;11530.76938461243;11635.53457861676 08:29:18 INFO - PROCESS | 1657 | __end_tp_report 08:29:18 INFO - PROCESS | 1657 | __start_cc_report 08:29:18 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,1130 08:29:18 INFO - PROCESS | 1657 | __end_cc_report 08:29:18 INFO - PROCESS | 1657 | __startTimestamp1467905358636__endTimestamp 08:29:18 INFO - PROCESS | 1657 | 08:29:18 INFO - PROCESS | 1657 | ------- Summary: start ------- 08:29:18 INFO - PROCESS | 1657 | Number of tests: 6 08:29:18 INFO - PROCESS | 1657 | 08:29:18 INFO - PROCESS | 1657 | [#0] dojo.html Cycles:165 Average:5739.34 Median:5889.68 stddev:646.77 (11.0%) stddev-sans-first:620.60 08:29:18 INFO - PROCESS | 1657 | Values: 3333.1 3346.8 3369.9 3383.0 3401.1 5585.8 5609.9 5651.7 5698.5 5746.6 5688.5 5729.8 5803.6 5820.3 5864.0 5153.9 5173.3 5237.4 5247.8 5339.1 4454.8 5302.3 5306.3 5362.6 5387.2 5133.3 5158.5 5192.3 5245.1 5261.1 5222.8 5226.3 5231.8 5353.4 5372.6 5221.9 5235.8 5253.9 5311.3 5326.8 5578.9 5646.4 5732.2 5757.5 5804.2 6140.5 6207.7 6223.7 6243.0 6261.6 6279.8 6323.3 6331.1 6349.7 6375.1 5004.5 5585.4 5838.6 5865.8 5867.6 6280.2 6339.4 6339.7 6353.3 6408.0 6067.8 6101.1 6112.5 6116.5 6125.6 6118.1 6164.6 6263.5 6266.8 6332.8 6102.8 6138.3 6142.4 6142.7 6171.8 6253.1 6261.8 6267.8 6274.2 6284.7 4975.3 4977.3 4981.5 4991.3 5012.0 4313.2 4319.4 4401.5 4492.8 4523.3 5713.5 5724.0 5731.5 5737.3 5784.1 6290.8 6311.0 6313.8 6355.8 6360.5 5551.2 5647.5 5682.3 5689.5 5721.8 5443.9 5446.2 5452.2 5461.2 5462.7 6326.5 6331.8 6337.5 6339.5 6341.2 6134.5 6202.6 6211.8 6276.2 6309.7 6335.6 6349.6 6353.0 6353.8 6376.7 6224.1 6226.0 6230.2 6230.8 6314.5 6132.9 6150.8 6169.5 6220.8 6301.0 4799.5 5930.7 5977.2 6097.4 6105.2 5922.3 6091.7 6097.6 6123.0 6131.2 5475.6 5837.3 5856.4 5857.9 5891.6 6015.7 6038.8 6044.0 6050.9 6082.9 5785.2 5840.6 5889.7 5898.2 5911.5 08:29:18 INFO - PROCESS | 1657 | 08:29:18 INFO - PROCESS | 1657 | [#1] ext.html Cycles:180 Average:70916.63 Median:39204.71 stddev:151637.88 (386.8%) stddev-sans-first:151971.12 08:29:18 INFO - PROCESS | 1657 | Values: 511.6 513.9 541.9 546.1 553.4 30488.0 32380.5 41924.6 43045.1 43062.0 36145.0 44984.0 45071.8 45188.3 45197.9 9814.5 10013.8 10021.8 10026.7 10036.3 7500.4 9027.7 11968.7 12186.4 12194.7 12108.5 12179.6 12181.6 12187.1 12208.0 8738.5 8919.9 12092.6 12221.8 12226.3 8893.8 8941.1 11737.7 12022.0 12046.7 42035.0 42304.4 42402.6 42462.6 42477.4 3417.5 3473.8 3884.3 4116.2 4171.6 31805.0 32881.8 33012.7 33242.8 33253.2 3265.6 3393.9 3426.1 3457.0 3472.6 532634.0 535575.0 535867.0 536201.0 536938.0 62315.7 62417.7 62795.0 62805.7 62863.0 65683.7 65838.3 65857.0 65918.7 66071.0 16458.9 16717.8 16738.0 16746.2 16777.0 11730.2 11948.9 12604.2 13031.9 13109.5 16834.8 17397.8 17414.4 17423.8 17439.7 6108.0 6281.8 6397.8 6957.2 6981.8 947.3 953.9 995.1 1038.3 1042.7 26661.9 29250.7 30985.8 31110.4 31229.8 864.9 1060.5 1065.4 1176.5 1201.2 13708.5 15325.8 15466.5 15583.0 15900.4 571654.0 575439.0 576194.0 577852.1 577878.0 58843.7 59204.4 59239.1 59413.4 59491.1 1297.8 1402.3 1418.4 1436.4 1449.3 27048.0 31111.0 31299.7 31450.2 32294.5 43614.0 53102.7 53522.2 53642.5 53654.5 588916.0 593241.0 593709.0 594090.0 595811.0 26330.9 26815.9 27287.6 27450.6 28245.4 48483.5 49186.8 49586.3 49638.0 50190.5 39031.8 39984.6 40958.8 42085.6 42356.6 40880.6 41141.4 41562.2 41651.7 42164.6 39377.6 39683.6 39876.6 40038.2 40104.0 27731.4 31887.0 35115.8 36160.0 36410.0 25489.0 25854.0 26128.2 26622.7 26706.6 08:29:18 INFO - PROCESS | 1657 | 08:29:18 INFO - PROCESS | 1657 | [#2] jquery.html Cycles:180 Average:34846.29 Median:2224.05 stddev:100111.76 (4501.3%) stddev-sans-first:100359.05 08:29:18 INFO - PROCESS | 1657 | Values: 323.9 362.6 389.2 394.7 412.0 15200.0 15757.7 21291.4 21554.8 21582.8 20516.5 21142.8 21156.8 21186.8 21197.5 5746.7 5934.6 6171.3 6180.5 6186.1 6013.5 6060.6 6102.0 6115.8 6122.9 6035.2 6048.7 6097.1 6689.3 6690.4 6572.4 6689.1 6713.5 6719.0 6746.8 5822.9 5856.9 5895.2 5898.2 5907.5 12868.8 13062.2 19102.6 25130.9 25179.7 2198.7 2249.4 2251.3 2324.2 2704.9 9785.4 9926.9 10393.8 12004.3 14718.1 1654.9 1824.1 1886.3 1923.1 1942.7 101660.0 121263.0 125703.0 128087.0 135888.0 10317.3 10568.8 11025.0 11305.0 11394.9 14159.1 14182.7 14298.0 14305.7 14313.0 7384.3 7929.6 8191.8 8380.8 8514.7 8294.5 8336.5 8345.3 8393.5 8451.1 3618.3 3627.0 4439.2 4441.8 4501.5 1573.6 1847.8 1887.2 1888.2 1895.3 895.3 941.4 988.3 998.8 1021.2 14712.9 20275.4 20529.1 21118.3 21235.5 939.4 958.2 961.7 968.9 1027.0 10928.4 11050.5 11102.2 11116.3 11158.4 537417.0 586286.0 621521.0 623936.0 628074.0 75078.9 101485.0 125403.0 127719.4 131377.0 955.8 991.4 1037.4 1052.1 1058.2 19254.0 19466.7 19592.6 19598.9 19629.8 55567.4 55888.0 56233.7 58586.4 58880.0 81487.2 81560.2 82482.6 83580.6 83816.6 6934.8 7014.9 7309.7 7399.7 8126.2 11016.7 13068.9 13178.3 13393.5 13433.1 11402.3 11490.8 11495.4 11535.9 11674.9 11243.7 11469.9 11519.3 11537.3 11787.7 11689.9 11938.1 11940.3 11954.6 11980.4 7339.9 7387.7 7411.2 7468.7 7580.1 10656.4 11344.1 11520.7 11544.7 11567.8 08:29:18 INFO - PROCESS | 1657 | 08:29:18 INFO - PROCESS | 1657 | [#3] mootools.html Cycles:180 Average:5608.10 Median:5115.09 stddev:3129.61 (61.2%) stddev-sans-first:3123.56 08:29:18 INFO - PROCESS | 1657 | Values: 1553.4 1567.1 1757.2 1867.5 1896.0 8116.4 8365.9 8745.7 8755.9 8764.3 7656.5 7782.9 7910.8 7912.2 7997.3 2101.3 2271.8 2352.1 2352.7 2357.6 2327.0 2337.5 2358.9 2360.2 2366.0 2051.4 2338.0 2341.8 2343.1 2344.6 1912.4 2310.3 2328.8 2330.6 2334.3 2307.7 2322.0 2322.4 2323.4 2362.3 7447.7 8720.7 8775.0 8776.3 8816.0 5114.7 5204.2 5256.6 5261.1 5265.9 3462.4 3536.0 3599.6 3606.0 3610.6 1066.2 1081.1 1089.0 1105.3 1109.5 11284.5 11324.2 11333.7 11344.3 11366.0 3581.4 4528.5 4608.5 4614.7 4619.3 9700.4 9745.3 9758.5 9760.4 9770.8 5214.3 5233.7 5236.6 5239.9 5242.9 4175.9 4664.9 4788.2 4808.0 4823.9 3891.0 3948.5 4000.3 4002.3 4043.0 1061.7 1263.9 1265.4 1282.3 1282.6 863.0 911.4 935.5 942.8 943.9 4504.9 5115.5 5858.1 6056.5 6222.7 781.6 941.7 977.1 987.5 990.0 3821.9 3928.3 3955.9 3970.6 3970.6 10097.9 10500.4 10728.1 10782.0 10804.0 10609.2 10657.5 10746.5 10747.0 10762.4 7857.3 8202.8 8212.0 8223.9 8228.7 5709.4 5796.7 5857.4 5910.3 5964.8 9763.4 9832.0 9844.7 9860.5 9880.6 9622.3 9919.3 10020.7 10109.8 10139.8 6524.1 6712.7 6723.6 7030.1 7164.5 7889.3 7968.9 8120.7 8396.5 8441.2 7372.3 7464.5 7474.9 7602.5 7638.4 7325.1 7389.0 7396.6 7528.5 7557.5 6885.7 6936.8 6983.1 6999.4 7016.2 4856.6 5003.9 5047.5 5074.5 5120.7 4346.2 5046.2 5122.9 5152.1 5253.6 08:29:18 INFO - PROCESS | 1657 | 08:29:18 INFO - PROCESS | 1657 | [#4] prototype.html Cycles:180 Average:4698.04 Median:5310.90 stddev:1491.89 (28.1%) stddev-sans-first:1459.07 08:29:18 INFO - PROCESS | 1657 | Values: 298.5 304.5 314.6 316.4 317.8 5606.5 5607.2 5623.6 5632.9 5673.3 5257.9 5307.3 5435.7 5531.2 5545.4 4153.7 4201.6 4208.2 4244.4 4255.4 3483.4 4225.8 4234.6 4256.7 4269.5 3862.4 3900.9 4187.5 4196.8 4198.9 3849.0 4179.6 4187.4 4188.0 4193.2 3202.3 3648.8 3651.9 3686.4 3701.4 4335.4 4462.0 5286.8 5439.9 5486.3 3858.4 4429.0 4677.3 4812.2 4833.7 4905.7 5518.9 5854.4 5883.4 5901.1 3308.3 3782.5 3966.1 4041.4 4060.7 5025.9 5282.9 5689.7 6075.7 6083.2 3879.6 3993.5 3998.3 4019.3 4036.1 3253.9 3523.8 3750.3 4058.8 4068.3 4454.7 4491.0 4493.9 4704.6 4717.3 4867.5 4873.5 5846.3 5909.9 5910.3 1170.0 1226.9 1313.6 1370.5 1388.5 786.6 848.2 866.3 876.9 926.5 4534.6 5051.4 5107.9 5416.2 5458.8 4947.0 5958.8 5962.4 6000.3 6069.6 4693.7 5076.4 5381.7 5390.3 5400.7 2748.4 2797.0 2821.0 2994.9 3096.3 5868.2 5963.6 6176.6 6232.4 6327.4 6259.4 6296.8 6302.7 6335.7 6343.0 6060.4 6300.3 6306.8 6413.3 6424.3 5043.7 6080.5 6082.2 6121.7 6127.4 5077.6 5560.8 5827.9 6049.7 6055.8 5314.5 5331.3 5967.9 6280.9 6297.4 3649.6 3756.1 3961.5 4004.7 4049.3 5368.5 6140.3 6151.4 6156.9 6175.0 4938.5 5574.8 5583.8 5877.7 5896.3 4911.4 5859.7 5877.0 5880.1 5896.5 4523.9 5324.4 5429.4 5707.6 5845.8 5779.0 5840.6 5867.9 5871.9 5882.5 5452.5 5576.5 5767.8 5773.6 5789.5 08:29:18 INFO - PROCESS | 1657 | 08:29:18 INFO - PROCESS | 1657 | [#5] yui.html Cycles:180 Average:24370.69 Median:3540.23 stddev:38462.10 (1086.4%) stddev-sans-first:38528.41 08:29:18 INFO - PROCESS | 1657 | Values: 552.0 701.9 760.2 776.0 877.8 15139.6 15679.1 15733.8 16437.5 16477.2 12529.6 14599.1 14772.0 14806.8 15000.6 871.1 897.5 899.3 900.8 901.1 849.6 866.5 866.7 867.1 868.3 923.6 926.1 926.2 927.2 928.5 916.0 916.4 916.4 916.9 918.0 679.7 682.3 682.5 682.8 684.8 13331.3 13669.3 13671.7 13697.9 13714.9 12188.9 12219.6 12246.6 12516.9 12589.1 11030.9 11242.3 11250.0 11332.3 11734.4 3789.7 3792.8 3797.9 3824.4 3958.0 84233.6 87574.0 103200.0 111360.0 114887.0 11510.3 11857.9 11920.8 12056.2 12064.6 19519.6 21028.7 23784.9 23816.4 23858.5 6834.4 8190.9 8293.1 8346.3 8435.7 3843.5 4386.6 4468.3 4607.3 4752.7 2938.8 3404.1 3533.7 3539.5 3541.0 641.7 652.3 658.4 668.4 671.5 506.0 658.6 659.4 707.6 716.9 8296.3 8997.2 9234.7 9403.0 9744.6 603.6 632.9 652.4 659.4 706.4 4595.2 4600.2 4618.5 4645.9 4751.6 146865.0 147292.3 147344.0 152217.0 156196.2 85582.6 95152.5 120411.0 123631.0 130646.0 98763.0 107897.5 109472.5 113094.4 117103.0 8540.5 9676.4 9798.3 9823.2 9903.4 54065.0 58732.7 64825.4 65827.0 67789.7 96420.0 98536.0 100515.0 101159.5 102559.0 11520.9 13126.8 13525.7 13869.4 13937.6 15274.7 15334.1 15652.0 15709.5 15725.8 12999.4 13269.5 13319.6 13327.7 13762.1 13920.3 14213.8 14545.0 14575.3 14808.4 13702.7 13882.0 13967.7 14016.2 14038.6 9596.3 10737.8 11097.5 11589.1 11885.0 11432.5 11513.3 11514.2 11530.8 11635.5 08:29:18 INFO - PROCESS | 1657 | -------- Summary: end -------- 08:29:18 INFO - PROCESS | 1657 | 08:29:19 INFO - TEST-INFO | 1657: exit 0 08:29:19 INFO - TEST-OK | dromaeo_css | took 1115804ms 08:29:19 INFO - TEST-START | kraken 08:29:19 INFO - operating with platform_type : mac_ 08:29:19 INFO - Initialising browser for kraken test... 08:29:19 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/tmpyK3NHM/profile http://localhost:49271/getInfo.html) 08:29:21 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 08:29:21 INFO - PROCESS | 1663 | colorDepth:24 08:29:21 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 08:29:21 INFO - PROCESS | 1663 | __metrics 08:29:21 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" 08:29:21 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] 08:29:22 INFO - TEST-INFO | 1663: exit 0 08:29:22 INFO - Browser initialized. 08:29:22 INFO - Running cycle 1/1 for kraken test... 08:29:22 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/tmpyK3NHM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:29:34 INFO - PROCESS | 1664 | RSS: Main: 243785728 08:29:34 INFO - PROCESS | 1664 | 08:29:37 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 08:29:38 INFO - PROCESS | 1664 | RSS: Main: 293629952 08:29:38 INFO - PROCESS | 1664 | 08:29:40 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 08:29:41 INFO - PROCESS | 1664 | RSS: Main: 304033792 08:29:41 INFO - PROCESS | 1664 | 08:29:44 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 08:29:45 INFO - PROCESS | 1664 | RSS: Main: 388460544 08:29:45 INFO - PROCESS | 1664 | 08:29:47 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 08:29:48 INFO - PROCESS | 1664 | RSS: Main: 290148352 08:29:48 INFO - PROCESS | 1664 | 08:29:50 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:29:50 INFO - PROCESS | 1664 | RSS: Main: 293318656 08:29:50 INFO - PROCESS | 1664 | 08:29:53 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 08:29:54 INFO - PROCESS | 1664 | RSS: Main: 330547200 08:29:54 INFO - PROCESS | 1664 | 08:29:57 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 08:29:57 INFO - PROCESS | 1664 | RSS: Main: 333733888 08:29:57 INFO - PROCESS | 1664 | 08:30:00 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 08:30:01 INFO - PROCESS | 1664 | RSS: Main: 332902400 08:30:01 INFO - PROCESS | 1664 | 08:30:02 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:30:03 INFO - PROCESS | 1664 | RSS: Main: 311336960 08:30:03 INFO - PROCESS | 1664 | 08:30:05 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:30:05 INFO - PROCESS | 1664 | RSS: Main: 317984768 08:30:05 INFO - PROCESS | 1664 | 08:30:08 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:30:08 INFO - PROCESS | 1664 | RSS: Main: 315379712 08:30:08 INFO - PROCESS | 1664 | 08:30:11 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:30:12 INFO - PROCESS | 1664 | RSS: Main: 324476928 08:30:12 INFO - PROCESS | 1664 | 08:30:15 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:30:15 INFO - PROCESS | 1664 | RSS: Main: 325984256 08:30:15 INFO - PROCESS | 1664 | 08:30:17 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 08:30:17 INFO - PROCESS | 1664 | RSS: Main: 369758208 08:30:17 INFO - PROCESS | 1664 | 08:30:17 INFO - PROCESS | 1664 | __start_tp_report 08:30:17 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 08:30:17 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 08:30:17 INFO - PROCESS | 1664 | |i|pagename|runs| 08:30:17 INFO - PROCESS | 1664 | |0;ai-astar;102;115;114;113;114;114;114;114;114;114 08:30:17 INFO - PROCESS | 1664 | |1;audio-beat-detection;146;113;108;155;107;105;129;112;174;136 08:30:17 INFO - PROCESS | 1664 | |2;audio-dft;184;184;183;182;185;183;182;184;182;185 08:30:17 INFO - PROCESS | 1664 | |3;audio-fft;78;73;57;107;58;58;62;60;126;56 08:30:17 INFO - PROCESS | 1664 | |4;audio-oscillator;83;87;92;71;71;68;98;70;68;99 08:30:17 INFO - PROCESS | 1664 | |5;imaging-gaussian-blur;90;87;91;88;90;86;91;88;90;87 08:30:17 INFO - PROCESS | 1664 | |6;imaging-darkroom;95;95;103;151;128;95;95;95;95;96 08:30:17 INFO - PROCESS | 1664 | |7;imaging-desaturate;83;86;85;87;85;86;84;88;85;85 08:30:17 INFO - PROCESS | 1664 | |8;json-parse-financial;54;53;57;58;55;56;54;54;55;56 08:30:17 INFO - PROCESS | 1664 | |9;json-stringify-tinderbox;66;65;71;76;74;72;72;71;68;69 08:30:17 INFO - PROCESS | 1664 | |10;stanford-crypto-aes;85;97;100;103;89;81;85;84;82;83 08:30:17 INFO - PROCESS | 1664 | |11;stanford-crypto-ccm;137;135;136;136;161;149;149;136;185;136 08:30:17 INFO - PROCESS | 1664 | |12;stanford-crypto-pbkdf2;167;173;164;165;165;162;163;166;162;164 08:30:17 INFO - PROCESS | 1664 | |13;stanford-crypto-sha256-iterative;65;67;68;67;68;66;67;68;67;67 08:30:17 INFO - PROCESS | 1664 | __end_tp_report 08:30:17 INFO - PROCESS | 1664 | __start_cc_report 08:30:17 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,2096 08:30:17 INFO - PROCESS | 1664 | __end_cc_report 08:30:17 INFO - PROCESS | 1664 | __startTimestamp1467905417691__endTimestamp 08:30:17 INFO - PROCESS | 1664 | 08:30:17 INFO - PROCESS | 1664 | ------- Summary: start ------- 08:30:17 INFO - PROCESS | 1664 | Number of tests: 14 08:30:17 INFO - PROCESS | 1664 | 08:30:17 INFO - PROCESS | 1664 | [#0] ai-astar Cycles:10 Average:112.80 Median:114.00 stddev:3.82 (3.4%) stddev-sans-first:0.50 08:30:17 INFO - PROCESS | 1664 | Values: 102.0 115.0 114.0 113.0 114.0 114.0 114.0 114.0 114.0 114.0 08:30:17 INFO - PROCESS | 1664 | 08:30:17 INFO - PROCESS | 1664 | [#1] audio-beat-detection Cycles:10 Average:128.50 Median:132.50 stddev:23.76 (17.9%) stddev-sans-first:24.35 08:30:17 INFO - PROCESS | 1664 | Values: 146.0 113.0 108.0 155.0 107.0 105.0 129.0 112.0 174.0 136.0 08:30:17 INFO - PROCESS | 1664 | 08:30:17 INFO - PROCESS | 1664 | [#2] audio-dft Cycles:10 Average:183.40 Median:184.00 stddev:1.17 (0.6%) stddev-sans-first:1.22 08:30:17 INFO - PROCESS | 1664 | Values: 184.0 184.0 183.0 182.0 185.0 183.0 182.0 184.0 182.0 185.0 08:30:17 INFO - PROCESS | 1664 | 08:30:17 INFO - PROCESS | 1664 | [#3] audio-fft Cycles:10 Average:73.50 Median:59.00 stddev:24.20 (41.0%) stddev-sans-first:25.62 08:30:17 INFO - PROCESS | 1664 | Values: 78.0 73.0 57.0 107.0 58.0 58.0 62.0 60.0 126.0 56.0 08:30:17 INFO - PROCESS | 1664 | 08:30:17 INFO - PROCESS | 1664 | [#4] audio-oscillator Cycles:10 Average:80.70 Median:85.00 stddev:12.61 (14.8%) stddev-sans-first:13.35 08:30:17 INFO - PROCESS | 1664 | Values: 83.0 87.0 92.0 71.0 71.0 68.0 98.0 70.0 68.0 99.0 08:30:17 INFO - PROCESS | 1664 | 08:30:17 INFO - PROCESS | 1664 | [#5] imaging-gaussian-blur Cycles:10 Average:88.80 Median:90.00 stddev:1.81 (2.0%) stddev-sans-first:1.87 08:30:17 INFO - PROCESS | 1664 | Values: 90.0 87.0 91.0 88.0 90.0 86.0 91.0 88.0 90.0 87.0 08:30:17 INFO - PROCESS | 1664 | 08:30:17 INFO - PROCESS | 1664 | [#6] imaging-darkroom Cycles:10 Average:104.80 Median:95.00 stddev:19.23 (20.2%) stddev-sans-first:20.07 08:30:17 INFO - PROCESS | 1664 | Values: 95.0 95.0 103.0 151.0 128.0 95.0 95.0 95.0 95.0 96.0 08:30:17 INFO - PROCESS | 1664 | 08:30:17 INFO - PROCESS | 1664 | [#7] imaging-desaturate Cycles:10 Average:85.40 Median:85.50 stddev:1.43 (1.7%) stddev-sans-first:1.22 08:30:17 INFO - PROCESS | 1664 | Values: 83.0 86.0 85.0 87.0 85.0 86.0 84.0 88.0 85.0 85.0 08:30:17 INFO - PROCESS | 1664 | 08:30:17 INFO - PROCESS | 1664 | [#8] json-parse-financial Cycles:10 Average:55.20 Median:55.50 stddev:1.55 (2.8%) stddev-sans-first:1.58 08:30:17 INFO - PROCESS | 1664 | Values: 54.0 53.0 57.0 58.0 55.0 56.0 54.0 54.0 55.0 56.0 08:30:17 INFO - PROCESS | 1664 | 08:30:17 INFO - PROCESS | 1664 | [#9] json-stringify-tinderbox Cycles:10 Average:70.40 Median:71.50 stddev:3.44 (4.8%) stddev-sans-first:3.26 08:30:17 INFO - PROCESS | 1664 | Values: 66.0 65.0 71.0 76.0 74.0 72.0 72.0 71.0 68.0 69.0 08:30:17 INFO - PROCESS | 1664 | 08:30:17 INFO - PROCESS | 1664 | [#10] stanford-crypto-aes Cycles:10 Average:88.90 Median:84.50 stddev:8.08 (9.6%) stddev-sans-first:8.44 08:30:17 INFO - PROCESS | 1664 | Values: 85.0 97.0 100.0 103.0 89.0 81.0 85.0 84.0 82.0 83.0 08:30:17 INFO - PROCESS | 1664 | 08:30:17 INFO - PROCESS | 1664 | [#11] stanford-crypto-ccm Cycles:10 Average:146.00 Median:143.00 stddev:16.21 (11.3%) stddev-sans-first:16.87 08:30:17 INFO - PROCESS | 1664 | Values: 137.0 135.0 136.0 136.0 161.0 149.0 149.0 136.0 185.0 136.0 08:30:17 INFO - PROCESS | 1664 | 08:30:17 INFO - PROCESS | 1664 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.10 Median:165.00 stddev:3.21 (1.9%) stddev-sans-first:3.33 08:30:17 INFO - PROCESS | 1664 | Values: 167.0 173.0 164.0 165.0 165.0 162.0 163.0 166.0 162.0 164.0 08:30:17 INFO - PROCESS | 1664 | 08:30:17 INFO - PROCESS | 1664 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:67.00 stddev:0.94 (1.4%) stddev-sans-first:0.67 08:30:17 INFO - PROCESS | 1664 | Values: 65.0 67.0 68.0 67.0 68.0 66.0 67.0 68.0 67.0 67.0 08:30:17 INFO - PROCESS | 1664 | -------- Summary: end -------- 08:30:17 INFO - PROCESS | 1664 | 08:30:18 INFO - TEST-INFO | 1664: exit 0 08:30:18 INFO - TEST-OK | kraken | took 59319ms 08:30:18 INFO - SUITE-END | took 1175s 08:30:18 INFO - Completed test suite (00:19:35) 08:30:18 INFO - javascript benchmark 08:30:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3333.1167240681207, 3346.8493917773703, 3369.8652053917835, 3383.0, 3401.1327111586543, 5585.832425027245, 5609.915851262229, 5651.660900345983, 5698.487136157745, 5746.597738158331, 5688.459596338341, 5729.828105156842, 5803.6227645203035, 5820.272465941757, 5864.032434648278, 5153.948460515385, 5173.3016042834115, 5237.397699264595, 5247.816326428576, 5339.145736682131, 4454.844080457185, 5302.257683924254, 5306.283651707028, 5362.597805164609, 5387.19192121183, 5133.281340612336, 5158.484151584849, 5192.324997750268, 5245.055889939809, 5261.079311120577, 5222.817201397971, 5226.268322434862, 5231.764570594332, 5353.357597088336, 5372.623916325859, 5221.869453263661, 5235.790568377261, 5253.86865328366, 5311.336082989626, 5326.786928522854, 5578.944210557883, 5646.378898321182, 5732.197492351074, 5757.453041961007, 5804.216430781833, 6140.504248697391, 6207.658578778165, 6223.688815559204, 6243.001119820852, 6261.5929964552615, 6279.811605651837, 6323.272823625296, 6331.113644089831, 6349.714262858182, 6375.139356186902, 5004.474530174352, 5585.413531579207, 5838.591298609099, 5865.76536938522, 5867.647941123503, 6280.214973128359, 6339.397757213057, 6339.714712837934, 6353.30113687494, 6408.038794180817, 6067.848303792441, 6101.115338275925, 6112.541559383064, 6116.479212242861, 6125.571210015256, 6118.082287656798, 6164.630122192682, 6263.467605253513, 6266.811995640138, 6332.810015699444, 6102.786402475981, 6138.324784273726, 6142.375998161017, 6142.744345496893, 6171.783987560414, 6253.1245625612455, 6261.780837670751, 6267.843303917439, 6274.152989346471, 6284.748610055638, 4975.328330675385, 4977.278294647256, 4981.452040275567, 4991.276264941635, 5011.974940125276, 4313.245182093121, 4319.3952846601505, 4401.515833258339, 4492.797824097924, 4523.276275795923, 5713.485786279255, 5723.971380143156, 5731.484166425042, 5737.340205876378, 5784.074548072288, 6290.779822706183, 6311.0, 6313.810585682437, 6355.809325720235, 6360.4911607072245, 5551.167324901297, 5647.519960803378, 5682.346530149003, 5689.516391106719, 5721.77112915487, 5443.945560544423, 5446.210299506629, 5452.182172674051, 5461.15352120422, 5462.672239665632, 6326.49388048955, 6331.778387756406, 6337.493000559944, 6339.492840572835, 6341.207349081365, 6134.478569321657, 6202.596831205957, 6211.844703882439, 6276.152719382825, 6309.7160627770945, 6335.588186767845, 6349.587276826992, 6353.0, 6353.841153971188, 6376.713047912948, 6224.097505861715, 6226.034964580497, 6230.190075290183, 6230.844228894314, 6314.526410519137, 6132.8773424532155, 6150.7539698411565, 6169.50643948474, 6220.844478887883, 6301.0, 4799.544043315879, 5930.673812940329, 5977.163197152315, 6097.420745029215, 6105.206323177958, 5922.318933322551, 6091.664958427152, 6097.603655762361, 6122.969385153046, 6131.172291740558, 5475.589330800286, 5837.29952405714, 5856.3558008618165, 5857.941420585739, 5891.617044892068, 6015.699215039318, 6038.788642397495, 6044.032954727222, 6050.878982420415, 6082.90875636857, 5785.218995435563, 5840.620359676607, 5889.676067816311, 5898.233229680114, 5911.527077833849], "value": 5698.098003024621, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [511.6392942975202, 513.8766695992961, 541.9160030195321, 546.1098409592364, 553.4050895287567, 30488.0, 32380.514292285636, 41924.58075419249, 43045.13909721812, 43062.0, 36145.0, 44984.0, 45071.77464112683, 45188.3221751674, 45197.870053248684, 9814.509274536298, 10013.849792253122, 10021.799564008716, 10026.749331266703, 10036.29745917786, 7500.399968002546, 9027.729168124966, 11968.70078248042, 12186.390680465985, 12194.695132621711, 12108.455119519598, 12179.57371491998, 12181.634550963485, 12187.146899717023, 12207.962323202539, 8738.475691458532, 8919.910800891972, 12092.576759813408, 12221.816672749916, 12226.266424014542, 8893.822123557557, 8941.105889411007, 11737.706557336049, 12022.0, 12046.698832529257, 42035.0, 42304.365434518506, 42402.57597424017, 42462.57537424617, 42477.362839557434, 3417.470292104728, 3473.774204676713, 3884.261990221877, 4116.238495878242, 4171.561985991458, 31805.0, 32881.835590822135, 33012.6698733012, 33242.83378583092, 33253.16867078342, 3265.6407795142513, 3393.898183054512, 3426.109211604976, 3457.0320310313155, 3472.618012018676, 532634.0, 535575.0, 535867.0, 536201.0, 536938.0, 62315.6884215576, 62417.68791156106, 62795.0, 62805.68597156985, 62863.0, 65683.67158164179, 65838.34161658418, 65857.0, 65918.67040664767, 66071.0, 16458.917705411637, 16717.826682531195, 16738.0, 16746.246418910934, 16777.0, 11730.237534560227, 11948.880511194777, 12604.243745375306, 13031.869681303255, 13109.475620975054, 16834.831651683326, 17397.78141043911, 17414.390496332566, 17423.825761742475, 17439.65120697579, 6107.9616465201025, 6281.811545653546, 6397.776077837253, 6957.234704182536, 6981.765834932773, 947.2847999760137, 953.880764904387, 995.0845222395413, 1038.3406536849154, 1042.6559235452282, 26661.866690666422, 29250.683719232686, 30985.845070774503, 31110.40012797718, 31229.843850781053, 864.8572985457329, 1060.4909643371225, 1065.3874022436976, 1176.5411489519095, 1201.225209739713, 13708.52020179289, 15325.846741532441, 15466.458673946583, 15583.0, 15900.363985440454, 571654.0, 575439.0, 576194.0, 577852.1107394436, 577878.0, 58843.70578147082, 59204.40795592075, 59239.111413328836, 59413.40586594079, 59491.10763338553, 1297.8118172864881, 1402.2778269191374, 1418.44680574576, 1436.4397884825134, 1449.282605110477, 27048.0, 31111.0, 31299.687003130588, 31450.21374465565, 32294.515582266755, 43614.0, 53102.67243318948, 53522.197167043305, 53642.463575365306, 53654.463455364945, 588916.0, 593241.0, 593709.0, 594090.0295498495, 595811.0, 26330.86834565815, 26815.865920670272, 27287.590686139327, 27450.588241176, 28245.43509129847, 48483.51516484931, 49186.754066230875, 49586.25620615766, 49638.007239855724, 50190.498095018585, 39031.804840975616, 39984.600153998086, 40958.79520602378, 42085.57914420817, 42356.57643423526, 40880.59119408887, 41141.382879256234, 41562.1687566253, 41651.68052366037, 42164.578354216064, 39377.60622393739, 39683.60316396799, 39876.60123398729, 40038.1992360157, 40104.0, 27731.44537109287, 31887.043388698377, 35115.82442087875, 36160.0, 36410.0, 25489.0, 25854.0, 26128.216153515426, 26622.7337726628, 26706.59940100939], "value": 20643.234228158955, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [323.94169049571076, 362.633739922678, 389.1749491078912, 394.70594407166664, 411.9773412462315, 15200.0, 15757.684846303082, 21291.36125916225, 21554.814485203326, 21582.784172158274, 20516.48708782281, 21142.788572114314, 21156.788432115714, 21186.788132118676, 21197.470063248427, 5746.655200687972, 5934.643921364732, 6171.290301615327, 6180.505559555225, 6186.133941248207, 6013.488853447462, 6060.575759696823, 6101.969490152543, 6115.847103822395, 6122.877542449149, 6035.185249991261, 6048.697565121748, 6097.054956481752, 6689.264180940093, 6690.4313133383475, 6572.375624315682, 6689.130413046321, 6713.462922966175, 6719.0, 6746.763863264811, 5822.883542329172, 5856.853578660525, 5895.174675545427, 5898.203742494794, 5907.468327850471, 12868.806967895489, 13062.150960187557, 19102.61794764111, 25130.874345628337, 25179.748202517923, 2198.714167158259, 2249.3814201094665, 2251.2683377902085, 2324.2097686786574, 2704.8782804773637, 9785.412875227508, 9926.851097233548, 10393.84409233862, 12004.339761313124, 14718.116912985255, 1654.9007059576463, 1824.1335365701318, 1886.3303527247767, 1923.0961448119363, 1942.689169732836, 101659.98340016653, 121263.0, 125703.0, 128087.0, 135888.0, 10317.329373590692, 10568.78458977212, 11025.03098884401, 11305.00417389376, 11394.948909196259, 14159.079659822079, 14182.716345673029, 14298.0, 14305.713885722436, 14313.0, 7384.298491643285, 7929.643166057426, 8191.836163276701, 8380.832383352421, 8514.659413623509, 8294.460860044077, 8336.541490217975, 8345.332373410112, 8393.454425462325, 8451.11263317355, 3618.2582570572968, 3627.0, 4439.247666446824, 4441.822327106879, 4501.459824820977, 1573.622330640661, 1847.8429333506801, 1887.169645356039, 1888.1975160556983, 1895.3271588586128, 895.2927187521785, 941.3598752847992, 988.2538683294067, 998.8202123617818, 1021.1830535571513, 14712.926435367968, 20275.377969762383, 20529.076191571414, 21118.260860869796, 21235.46911327229, 939.4175611121012, 958.1999030809213, 961.6682244625594, 968.9467079310706, 1026.9948650256702, 10928.398938058324, 11050.474108660841, 11102.222844400976, 11116.333020018825, 11158.442077896072, 537417.0, 586286.0, 621521.0, 623936.0, 628074.0, 75078.8738168928, 101485.0, 125403.0, 127719.44561108726, 131377.0, 955.7849483866074, 991.4101109839634, 1037.3983089807894, 1052.0531521630442, 1058.2433560004479, 19253.956669099647, 19466.707999380305, 19592.60814783668, 19598.902005489883, 19629.8037019628, 55567.44432555623, 55888.0, 56233.71883140558, 58586.4141358581, 58880.0, 81487.18512815033, 81560.18439815525, 82482.58758706169, 83580.58209708912, 83816.58091709502, 6934.791956241321, 7014.894776578458, 7309.720798860285, 7399.704011839466, 8126.187381261827, 11016.669499915015, 13068.869311306766, 13178.275194864374, 13393.464261429433, 13433.126846754929, 11402.258853174517, 11490.82763758561, 11495.425228738697, 11535.884641153481, 11674.94162529182, 11243.718907027389, 11469.885301146882, 11519.30884146954, 11537.25007874519, 11787.705307367385, 11689.88946050124, 11938.1046421517, 11940.283582985048, 11954.58158964432, 11980.400979951142, 7339.853202935805, 7387.704491820267, 7411.184769675224, 7468.663910123949, 7580.090389153337, 10656.413897235727, 11344.149188810707, 11520.711982200177, 11544.653660390202, 11567.76864462723], "value": 9566.239454289665, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1553.4174684493314, 1567.059764141515, 1757.1829099468744, 1867.4771064102056, 1896.0235478728457, 8116.39127065471, 8365.874511882326, 8745.737627871174, 8755.912440875589, 8764.298856091506, 7656.502327348732, 7782.922170778291, 7910.762677119681, 7912.208779122099, 7997.320227780631, 2101.3275751759443, 2271.7501074881748, 2352.1061996441326, 2352.7411984681758, 2357.5874222011166, 2326.9650955235684, 2337.4740683346195, 2358.917437889674, 2360.173939121311, 2366.0181024874655, 2051.4358551398336, 2337.9766202338014, 2341.800946919514, 2343.121329501437, 2344.6248600223967, 1912.416712902564, 2310.318456055468, 2328.84862483938, 2330.6037973544367, 2334.299710086967, 2307.699999000119, 2322.0131444136173, 2322.372959300996, 2323.407531079564, 2362.303120579433, 7447.702091916317, 8720.694775682885, 8775.034746177915, 8776.254018408443, 8815.986161591434, 5114.667546609458, 5204.1673332266655, 5256.553192978601, 5261.0793111205385, 5265.894682106336, 3462.3767721810063, 3536.045267777711, 3599.6220396858216, 3605.981970090159, 3610.5667319921513, 1066.1768376076918, 1081.0972837680547, 1088.9782204355947, 1105.2760441910561, 1109.4619109731773, 11284.548618055267, 11324.207305488619, 11333.716657083638, 11344.262622929484, 11366.0, 3581.3553560358864, 4528.547145285445, 4608.470025946992, 4614.7461889595725, 4619.330197121399, 9700.417974921527, 9745.317827752131, 9758.463284519277, 9760.365576237522, 9770.804583908282, 5214.270002199695, 5233.685978841282, 5236.607254455931, 5239.869003274948, 5242.947570524246, 4175.91648167035, 4664.883377915511, 4788.186008378514, 4807.95192048075, 4823.879403014953, 3890.9610903891166, 3948.4866967294643, 4000.259951908848, 4002.2595819773437, 4043.0, 1061.7292590389402, 1263.9115261931577, 1265.3799638177227, 1282.3331867429072, 1282.6152154353656, 862.981681615687, 911.3757076402685, 935.4714586258742, 942.7548837302351, 943.9433633981984, 4504.864854054384, 5115.514026167508, 5858.091995740667, 6056.515478761688, 6222.657753823492, 781.5662307419436, 941.6939494664261, 977.1401166973018, 987.5457289646699, 989.9653512127041, 3821.8662346817728, 3928.2929072767174, 3955.881323560298, 3970.583088775637, 3970.622790834866, 10097.899021009844, 10500.422476763852, 10728.088112510368, 10782.029617334321, 10804.0, 10609.15126789855, 10657.467126643636, 10746.462676866126, 10746.979036991474, 10762.3542587445, 7857.253560911704, 8202.794930126794, 8212.0, 8223.917760822435, 8228.711995080144, 5709.400512946081, 5796.710164491674, 5857.355690873916, 5910.290765108214, 5964.7912323068485, 9763.365381250196, 9832.01679832011, 9844.704658860246, 9860.506974651382, 9880.555375008285, 9622.326437149331, 9919.305648604817, 10020.749481263027, 10109.79780404373, 10139.79720405573, 6524.119243902012, 6712.664366781543, 6723.562968407038, 7030.086088808423, 7164.534305270141, 7889.329407000239, 7968.880466793119, 8120.715774947848, 8396.538190399586, 8441.155884411755, 7372.299631534996, 7464.514806537558, 7474.887876681963, 7602.50583712057, 7638.427117966286, 7325.084364454443, 7389.0, 7396.593187374746, 7528.548287102792, 7557.508761930457, 6885.724571017103, 6936.826579335557, 6983.127109111361, 6999.440044796303, 7016.22821489646, 4856.562909338035, 5003.899922001612, 5047.470015648304, 5074.517920797518, 5120.743962801919, 4346.174226896879, 5046.243063540498, 5122.871928201825, 5152.149895267239, 5253.579713622978], "value": 4525.090428770414, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.54024801805207, 304.50517908398854, 314.6051705110086, 316.3609508792238, 317.8156669131904, 5606.467385598372, 5607.243022192008, 5623.606347555673, 5632.915506267409, 5673.2908386451745, 5257.947420525794, 5307.283516725241, 5435.668261275993, 5531.197976293435, 5545.3622833374175, 4153.709240353177, 4201.6008479194425, 4208.200441916041, 4244.363345498169, 4255.40424340591, 3483.4078206704794, 4225.767288024664, 4234.576542345756, 4256.702030857826, 4269.487661480611, 3862.4206369044728, 3900.8829735107993, 4187.497500299968, 4196.790160491979, 4198.916021679564, 3849.0, 4179.644730197906, 4187.371894215861, 4188.0, 4193.182329445759, 3202.343519578504, 3648.835802388899, 3651.926961460756, 3686.4286035664513, 3701.3892707703467, 4335.436393268855, 4462.0, 5286.814961476368, 5439.945600543983, 5486.341639003305, 3858.3633700439395, 4428.98133429313, 4677.298405239208, 4812.205986012304, 4833.734144622044, 4905.681130726492, 5518.896220755824, 5854.385289544579, 5883.411658834125, 5901.078965911858, 3308.3217940322174, 3782.546094468654, 3966.0566322502054, 4041.4341992121126, 4060.7360521566, 5025.894303253279, 5282.867928301785, 5689.658620482743, 6075.726592303313, 6083.239595050619, 3879.6120387960977, 3993.542357039718, 3998.28030954431, 4019.3167161582014, 4036.0918793271867, 3253.8936761501204, 3523.7842944184217, 3750.268697603941, 4058.817353219112, 4068.3287257602756, 4454.710443821141, 4490.955090449053, 4493.876530867283, 4704.600108990706, 4717.315989181549, 4867.488913664085, 4873.488283730228, 5846.327672317738, 5909.852253693692, 5910.34986151523, 1169.9707507312385, 1226.877312268766, 1313.638749343919, 1370.4929176204669, 1388.5140200929593, 786.6342150899859, 848.1730312944829, 866.2853146154448, 876.9476628046369, 926.4811705444972, 4534.569215924482, 5051.358308549736, 5107.901801112705, 5416.187571864252, 5458.781648734085, 4946.950530494649, 5958.761649534057, 5962.40375962409, 6000.33996260411, 6069.605475644069, 4693.741844198602, 5076.390833100052, 5381.730913454312, 5390.328998010551, 5400.702961337086, 2748.40909204522, 2796.9930824903017, 2820.9858950705116, 2994.876921154567, 3096.349766549005, 5868.215001324122, 5963.5825492216, 6176.552881925007, 6232.407921247474, 6327.3988971047675, 6259.405356491126, 6296.842578935563, 6302.684865756693, 6335.714892829834, 6343.0, 6060.393960603904, 6300.30696623371, 6306.810795676137, 6413.294537600954, 6424.3254458282145, 5043.697378157322, 6080.537382360151, 6082.232584174279, 6121.663308518075, 6127.387261273837, 5077.568406685473, 5560.805371811967, 5827.863566604471, 6049.697515124314, 6055.788047418319, 5314.5216930476445, 5331.280277162688, 5967.910481342697, 6280.905786413299, 6297.433231009231, 3649.5620525537106, 3756.079760458705, 3961.4652021976663, 4004.719669623215, 4049.3318602430268, 5368.543673787772, 6140.267580926445, 6151.354107818794, 6156.907646385397, 6175.0, 4938.506149385033, 5574.832755017356, 5583.832485025456, 5877.73550190251, 5896.262967129109, 4911.435184953776, 5859.736311866061, 5877.0, 5880.0889710845, 5896.49639341965, 4523.90952180948, 5324.414314425488, 5429.40276569585, 5707.6290041147195, 5845.824625261249, 5778.9547316877515, 5840.620359676607, 5867.946218823548, 5871.911921321098, 5882.499962509532, 5452.509274165344, 5576.525995290446, 5767.769289228551, 5773.624714393551, 5789.478946894799], "value": 4215.1131386880215, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [552.0036334416379, 701.9473539484537, 760.1979911193688, 775.9728409505665, 877.8376000439919, 15139.621509462255, 15679.137647429401, 15733.842661573366, 16437.506874793744, 16477.17614119295, 12529.624111276653, 14599.124052556828, 14772.0, 14806.81545476362, 15000.624984375396, 871.1114663043708, 897.5332826929993, 899.3075331994361, 900.7703035725881, 901.0808974845653, 849.5667209723024, 866.5147517390282, 866.7009881590841, 867.111211008718, 868.288003836854, 923.5843870258377, 926.0878035135386, 926.1618235496886, 927.1933417926414, 928.4800511713428, 915.9725208243697, 916.3585490156884, 916.3585490156884, 916.9037251088673, 917.9993806750674, 679.7145199016376, 682.2801943949132, 682.5188242289174, 682.7610336382276, 684.7979845945458, 13331.333433328391, 13669.316534173251, 13671.658208544766, 13697.931510342385, 13714.862851371458, 12188.939055304756, 12219.572314969022, 12246.632601021984, 12516.874831251662, 12589.055820813475, 11030.944845275722, 11242.325460472397, 11249.987501124939, 11332.320060796379, 11734.354610496419, 3789.7157713171346, 3792.829322680486, 3797.905052373713, 3824.368979118442, 3957.960420395788, 84233.57883210544, 87574.0, 103199.99999999924, 111360.0, 114887.0, 11510.251833630788, 11857.940710296394, 11920.76158476843, 12056.216345937486, 12064.638060858188, 19519.609607807764, 21028.684569731467, 23784.881075594512, 23816.40458988539, 23858.52282954331, 6834.384905358542, 8190.8771368429525, 8293.129221431664, 8346.332293416634, 8435.662573497115, 3843.5003449551377, 4386.649068074546, 4468.262736648417, 4607.285870690049, 4752.714837109785, 2938.7795915306438, 3404.097914052778, 3533.699635530957, 3539.4513850353237, 3541.0, 641.7016087519326, 652.292262894755, 658.3811217455576, 668.3951024322995, 671.4796033074309, 505.99241011384856, 658.5719282466436, 659.3735950846715, 707.5966698981531, 716.8745469542913, 8296.25333719956, 8997.19025287714, 9234.676786312446, 9403.0, 9744.561494732754, 603.5563860562484, 632.8987959000841, 652.3737212276174, 659.36700767263, 706.4489698035541, 4595.241785105421, 4600.194965880957, 4618.51505591908, 4645.9303110453375, 4751.6436267279405, 146865.0, 147292.26353868376, 147344.0, 152217.0, 156196.21901890417, 85582.57208713917, 95152.52423737837, 120411.0, 123631.0, 130646.0, 98763.0, 107897.4605126985, 109472.4526377363, 113094.43452782519, 117103.0, 8540.487570745776, 9676.425575895088, 9798.31411801167, 9823.165030972448, 9903.356281841658, 54065.0, 58732.70633646975, 64825.37936551624, 65827.0, 67789.66105169442, 96420.0, 98536.0, 100515.0, 101159.49420252851, 102559.0, 11520.94239528797, 13126.803097953729, 13525.661858453617, 13869.445222191, 13937.58187254384, 15274.694506110038, 15334.079955202724, 15652.0, 15709.528714138594, 15725.842741572438, 12999.350032498527, 13269.535566255136, 13319.600411987656, 13327.733445330845, 13762.105463144864, 13920.303984800921, 14213.786793198316, 14544.98185127031, 14575.344109514885, 14808.407663693331, 13702.65743356424, 13882.0, 13967.650808729863, 14016.229107399191, 14038.649033774238, 9596.256871215264, 10737.838932415863, 11097.500612472297, 11589.0888906513, 11885.0, 11432.542698291974, 11513.30920144794, 11514.20263923053, 11530.76938461243, 11635.53457861676], "value": 7839.321180917117, "unit": "score"}], "name": "dromaeo_css", "value": 7430.220490712185, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 115.0, 114.0, 113.0, 114.0, 114.0, 114.0, 114.0, 114.0, 114.0], "value": 112.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 113.0, 108.0, 155.0, 107.0, 105.0, 129.0, 112.0, 174.0, 136.0], "value": 128.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 183.0, 182.0, 185.0, 183.0, 182.0, 184.0, 182.0, 185.0], "value": 183.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 73.0, 57.0, 107.0, 58.0, 58.0, 62.0, 60.0, 126.0, 56.0], "value": 73.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 87.0, 92.0, 71.0, 71.0, 68.0, 98.0, 70.0, 68.0, 99.0], "value": 80.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 91.0, 88.0, 90.0, 86.0, 91.0, 88.0, 90.0, 87.0], "value": 88.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 103.0, 151.0, 128.0, 95.0, 95.0, 95.0, 95.0, 96.0], "value": 104.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 85.0, 87.0, 85.0, 86.0, 84.0, 88.0, 85.0, 85.0], "value": 85.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 57.0, 58.0, 55.0, 56.0, 54.0, 54.0, 55.0, 56.0], "value": 55.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 71.0, 76.0, 74.0, 72.0, 72.0, 71.0, 68.0, 69.0], "value": 70.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 97.0, 100.0, 103.0, 89.0, 81.0, 85.0, 84.0, 82.0, 83.0], "value": 88.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 135.0, 136.0, 136.0, 161.0, 149.0, 149.0, 136.0, 185.0, 136.0], "value": 146.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 173.0, 164.0, 165.0, 165.0, 162.0, 163.0, 166.0, 162.0, 164.0], "value": 165.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 68.0, 67.0, 68.0, 66.0, 67.0, 68.0, 67.0, 67.0], "value": 67.0, "unit": "score"}], "name": "kraken", "value": 1450.5, "alertThreshold": 2.0}]} 08:30:18 INFO - Return code: 0 08:30:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:30:18 INFO - # TBPL SUCCESS # 08:30:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:30:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:30:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:30:19 INFO - 2016-07-07 08:30:19.012 screenresolution[1669:9799] starting screenresolution argv=screenresolution get 08:30:19 INFO - 2016-07-07 08:30:19.014 screenresolution[1669:9799] Display 0: 1600x1200x32@60 08:30:19 INFO - 2016-07-07 08:30:19.020 screenresolution[1670:9803] starting screenresolution argv=screenresolution list 08:30:19 INFO - Available Modes on Display 0 08:30:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:30:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:30:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:30:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:30:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:30:19 INFO - Intel Iris: 08:30:19 INFO - Chipset Model: Intel Iris 08:30:19 INFO - Type: GPU 08:30:19 INFO - Bus: Built-In 08:30:19 INFO - VRAM (Dynamic, Max): 1536 MB 08:30:19 INFO - Vendor: Intel (0x8086) 08:30:19 INFO - Device ID: 0x0a2e 08:30:19 INFO - Revision ID: 0x0009 08:30:19 INFO - Displays: 08:30:19 INFO - Display: 08:30:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:30:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:30:19 INFO - Display Serial Number: 1600x1200 60 08:30:19 INFO - Main Display: Yes 08:30:19 INFO - Mirror: Off 08:30:19 INFO - Online: Yes 08:30:19 INFO - Rotation: Supported 08:30:19 INFO - Return code: 0 08:30:19 INFO - Running post-action listener: _resource_record_post_action 08:30:19 INFO - ##### 08:30:19 INFO - ##### Finished run-tests step (success) 08:30:19 INFO - ##### 08:30:19 INFO - Running post-run listener: _resource_record_post_run 08:30:19 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 36471296; Write bytes: 704888320; Read time: 376; Write time: 1788 08:30:19 INFO - TinderboxPrint: CPU usage
53.4% 08:30:19 INFO - TinderboxPrint: I/O read bytes / time
36,471,296 / 376 08:30:19 INFO - TinderboxPrint: I/O write bytes / time
704,888,320 / 1,788 08:30:19 INFO - TinderboxPrint: CPU idle
2,255.9 (46.6%) 08:30:19 INFO - TinderboxPrint: CPU system
968.9 (20.0%) 08:30:19 INFO - TinderboxPrint: CPU user
1,620.8 (33.4%) 08:30:19 INFO - TinderboxPrint: Swap in / out
444,239,872 / 0 08:30:19 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 408109568; Write bytes: 410560512; Read time: 30587; Write time: 741 08:30:19 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 28850688; Write bytes: 293025280; Read time: 284; Write time: 1042 08:30:19 INFO - Running post-run listener: _upload_blobber_files 08:30:19 INFO - Blob upload gear active. 08:30:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:30:19 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 08:30:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:30:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:30:20 INFO - (blobuploader) - INFO - Open directory for files ... 08:30:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:30:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:30:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:30:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:30:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:30:20 INFO - (blobuploader) - INFO - Done attempting. 08:30:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:30:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:30:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:30:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:30:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:30:21 INFO - (blobuploader) - INFO - Done attempting. 08:30:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:30:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:30:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:30:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:30:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:30:24 INFO - (blobuploader) - INFO - Done attempting. 08:30:24 INFO - (blobuploader) - INFO - Iteration through files over. 08:30:24 INFO - Return code: 0 08:30:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:30:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:30:24 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4db2dd555ed88693cc848414dca4e1ced9e29b0ce65ff7275b167b8c92b253dcc0ec7d4675ab9db3a8d3edad8f0174e125b0bb1113f1cf635284b203e6b30e0a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d920db8968e08101668ec39011a1d8e24dd74902f94004270d8ca012c8d2a20550c918fccc25867b77ce3f85869dbc140fb8d524d473ad3d43b7cb48ef10e8f9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/aa7508a55b17719886f8fb73f552a2a76abe34ba2d034771ee32d108fd326325418e0fb9cf8078c5551243817e733cd764b84f17f5d73cca81a536cddab863b1"} 08:30:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:30:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:30:24 INFO - Contents: 08:30:24 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4db2dd555ed88693cc848414dca4e1ced9e29b0ce65ff7275b167b8c92b253dcc0ec7d4675ab9db3a8d3edad8f0174e125b0bb1113f1cf635284b203e6b30e0a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d920db8968e08101668ec39011a1d8e24dd74902f94004270d8ca012c8d2a20550c918fccc25867b77ce3f85869dbc140fb8d524d473ad3d43b7cb48ef10e8f9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/aa7508a55b17719886f8fb73f552a2a76abe34ba2d034771ee32d108fd326325418e0fb9cf8078c5551243817e733cd764b84f17f5d73cca81a536cddab863b1"} 08:30:24 INFO - Running post-run listener: copy_logs_to_upload_dir 08:30:24 INFO - Copying logs to upload dir... 08:30:24 INFO - mkdir: /builds/slave/test/build/upload/logs 08:30:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1279.173841 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-07-07 08:30:24.187110) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-07 08:30:24.190578) ========= 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.9PESWhxtRR/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.gJuvkKr4qo/Listeners TMPDIR=/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4db2dd555ed88693cc848414dca4e1ced9e29b0ce65ff7275b167b8c92b253dcc0ec7d4675ab9db3a8d3edad8f0174e125b0bb1113f1cf635284b203e6b30e0a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d920db8968e08101668ec39011a1d8e24dd74902f94004270d8ca012c8d2a20550c918fccc25867b77ce3f85869dbc140fb8d524d473ad3d43b7cb48ef10e8f9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/aa7508a55b17719886f8fb73f552a2a76abe34ba2d034771ee32d108fd326325418e0fb9cf8078c5551243817e733cd764b84f17f5d73cca81a536cddab863b1"} build_url:https://queue.taskcluster.net/v1/task/FqgNp5jFTNu-hqS3bwx7iw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010502 build_url: 'https://queue.taskcluster.net/v1/task/FqgNp5jFTNu-hqS3bwx7iw/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4db2dd555ed88693cc848414dca4e1ced9e29b0ce65ff7275b167b8c92b253dcc0ec7d4675ab9db3a8d3edad8f0174e125b0bb1113f1cf635284b203e6b30e0a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/d920db8968e08101668ec39011a1d8e24dd74902f94004270d8ca012c8d2a20550c918fccc25867b77ce3f85869dbc140fb8d524d473ad3d43b7cb48ef10e8f9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/aa7508a55b17719886f8fb73f552a2a76abe34ba2d034771ee32d108fd326325418e0fb9cf8078c5551243817e733cd764b84f17f5d73cca81a536cddab863b1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-07 08:30:24.225579) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 08:30:24.225906) ========= 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.9PESWhxtRR/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.gJuvkKr4qo/Listeners TMPDIR=/var/folders/6j/k4rbm30d51j_ptbxwx2xwvqw00000w/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.005195 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 08:30:24.273964) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-07 08:30:24.274329) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-07 08:30:24.274673) ========= ========= Total master_lag: 1.20 =========